{"level":"warn","ts":"2026-05-24T12:58:08.781208Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-24T12:58:08.781313Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.240: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.240:2380","--initial-cluster=instance=https://199.204.45.240:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.240:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.240: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-05-24T12:58:08.781387Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-24T12:58:08.781416Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-05-24T12:58:08.781448Z","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-05-24T12:58:08.782057Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"]} {"level":"info","ts":"2026-05-24T12:58:08.782611Z","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.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.240: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-05-24T12:58:08.786783Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.968843ms"} {"level":"info","ts":"2026-05-24T12:58:08.797196Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e46287fb5aa4785","cluster-id":"cae704afa9813e87"} {"level":"info","ts":"2026-05-24T12:58:08.797292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=()"} {"level":"info","ts":"2026-05-24T12:58:08.79733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 0"} {"level":"info","ts":"2026-05-24T12:58:08.797361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e46287fb5aa4785 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-24T12:58:08.797383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 1"} {"level":"info","ts":"2026-05-24T12:58:08.797441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"warn","ts":"2026-05-24T12:58:08.804221Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-24T12:58:08.80742Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-24T12:58:08.809895Z","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-05-24T12:58:08.813028Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e46287fb5aa4785","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-24T12:58:08.814963Z","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-05-24T12:58:08.81515Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e46287fb5aa4785","initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-24T12:58:08.815173Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-24T12:58:08.815273Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-05-24T12:58:08.815286Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-05-24T12:58:08.815352Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e46287fb5aa4785","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-24T12:58:08.815523Z","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-05-24T12:58:08.815678Z","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-05-24T12:58:08.815713Z","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-05-24T12:58:08.81594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"info","ts":"2026-05-24T12:58:08.816059Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","added-peer-id":"4e46287fb5aa4785","added-peer-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-05-24T12:58:09.19793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 is starting a new election at term 1"} {"level":"info","ts":"2026-05-24T12:58:09.197989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-24T12:58:09.198032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgPreVoteResp from 4e46287fb5aa4785 at term 1"} {"level":"info","ts":"2026-05-24T12:58:09.198238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became candidate at term 2"} {"level":"info","ts":"2026-05-24T12:58:09.198334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgVoteResp from 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-05-24T12:58:09.198437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became leader at term 2"} {"level":"info","ts":"2026-05-24T12:58:09.198483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e46287fb5aa4785 elected leader 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-05-24T12:58:09.199373Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e46287fb5aa4785","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.240:2379]}","request-path":"/0/members/4e46287fb5aa4785/attributes","cluster-id":"cae704afa9813e87","publish-timeout":"7s"} {"level":"info","ts":"2026-05-24T12:58:09.199386Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-24T12:58:09.199481Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-24T12:58:09.199837Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-24T12:58:09.19999Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-24T12:58:09.200656Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","cluster-version":"3.5"} {"level":"info","ts":"2026-05-24T12:58:09.200808Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-24T12:58:09.200868Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-24T12:58:09.20281Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-24T12:58:09.203705Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-24T12:58:09.216934Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.240:2379"} {"level":"info","ts":"2026-05-24T12:58:10.595102Z","caller":"traceutil/trace.go:171","msg":"trace[339041653] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:5; }","duration":"122.055383ms","start":"2026-05-24T12:58:10.47302Z","end":"2026-05-24T12:58:10.595075Z","steps":["trace[339041653] 'read index received' (duration: 121.583544ms)","trace[339041653] 'applied index is now lower than readState.Index' (duration: 470.829µs)"],"step_count":2} {"level":"info","ts":"2026-05-24T12:58:10.595139Z","caller":"traceutil/trace.go:171","msg":"trace[68242917] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"123.363421ms","start":"2026-05-24T12:58:10.471749Z","end":"2026-05-24T12:58:10.595113Z","steps":["trace[68242917] 'process raft request' (duration: 122.767179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T12:58:10.595341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.302209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-24T12:58:10.595454Z","caller":"traceutil/trace.go:171","msg":"trace[53292682] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:3; }","duration":"122.458812ms","start":"2026-05-24T12:58:10.472972Z","end":"2026-05-24T12:58:10.595431Z","steps":["trace[53292682] 'agreement among raft nodes before linearized reading' (duration: 122.239527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T12:58:10.602471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.207068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-24T12:58:10.602532Z","caller":"traceutil/trace.go:171","msg":"trace[1953801945] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"122.29892ms","start":"2026-05-24T12:58:10.480216Z","end":"2026-05-24T12:58:10.602515Z","steps":["trace[1953801945] 'agreement among raft nodes before linearized reading' (duration: 122.163577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T12:58:10.603314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.039794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-24T12:58:10.603362Z","caller":"traceutil/trace.go:171","msg":"trace[2010287829] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"123.088585ms","start":"2026-05-24T12:58:10.480259Z","end":"2026-05-24T12:58:10.603348Z","steps":["trace[2010287829] 'agreement among raft nodes before linearized reading' (duration: 123.021564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T12:58:13.274919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.45567ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-05-24T12:58:13.275358Z","caller":"traceutil/trace.go:171","msg":"trace[54906799] linearizableReadLoop","detail":"{readStateIndex:212; appliedIndex:210; }","duration":"276.634711ms","start":"2026-05-24T12:58:12.998697Z","end":"2026-05-24T12:58:13.275332Z","steps":["trace[54906799] 'read index received' (duration: 126.692901ms)","trace[54906799] 'applied index is now lower than readState.Index' (duration: 149.94081ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T12:58:13.275417Z","caller":"traceutil/trace.go:171","msg":"trace[851007810] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"283.106975ms","start":"2026-05-24T12:58:12.992272Z","end":"2026-05-24T12:58:13.275379Z","steps":["trace[851007810] 'process raft request' (duration: 282.768228ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T12:58:13.275606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.902606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-24T12:58:13.275631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.463277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3492"} {"level":"info","ts":"2026-05-24T12:58:13.275713Z","caller":"traceutil/trace.go:171","msg":"trace[80872491] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:206; }","duration":"276.576649ms","start":"2026-05-24T12:58:12.999116Z","end":"2026-05-24T12:58:13.275693Z","steps":["trace[80872491] 'agreement among raft nodes before linearized reading' (duration: 276.398846ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T12:58:13.275682Z","caller":"traceutil/trace.go:171","msg":"trace[1292362821] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:0; response_revision:206; }","duration":"277.011379ms","start":"2026-05-24T12:58:12.998658Z","end":"2026-05-24T12:58:13.27567Z","steps":["trace[1292362821] 'agreement among raft nodes before linearized reading' (duration: 276.787914ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T12:58:13.628661Z","caller":"traceutil/trace.go:171","msg":"trace[819848512] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"344.902143ms","start":"2026-05-24T12:58:13.283734Z","end":"2026-05-24T12:58:13.628637Z","steps":["trace[819848512] 'process raft request' (duration: 335.991459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T12:58:13.629283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T12:58:13.283718Z","time spent":"345.011025ms","remote":"127.0.0.1:35364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3695,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T12:58:13.629319Z","caller":"traceutil/trace.go:171","msg":"trace[1244486679] linearizableReadLoop","detail":"{readStateIndex:215; appliedIndex:213; }","duration":"247.689392ms","start":"2026-05-24T12:58:13.381596Z","end":"2026-05-24T12:58:13.629285Z","steps":["trace[1244486679] 'read index received' (duration: 237.987482ms)","trace[1244486679] 'applied index is now lower than readState.Index' (duration: 9.70095ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T12:58:13.629347Z","caller":"traceutil/trace.go:171","msg":"trace[440361399] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"318.358424ms","start":"2026-05-24T12:58:13.310963Z","end":"2026-05-24T12:58:13.629322Z","steps":["trace[440361399] 'process raft request' (duration: 318.221051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T12:58:13.629472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T12:58:13.310938Z","time spent":"318.457866ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":199,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-24T12:58:13.62966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.939188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T12:58:13.629713Z","caller":"traceutil/trace.go:171","msg":"trace[637068698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:209; }","duration":"248.150083ms","start":"2026-05-24T12:58:13.381546Z","end":"2026-05-24T12:58:13.629696Z","steps":["trace[637068698] 'agreement among raft nodes before linearized reading' (duration: 247.838606ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T12:58:44.836469Z","caller":"traceutil/trace.go:171","msg":"trace[637257055] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"198.463384ms","start":"2026-05-24T12:58:44.637986Z","end":"2026-05-24T12:58:44.83645Z","steps":["trace[637257055] 'process raft request' (duration: 196.733339ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T12:58:44.83899Z","caller":"traceutil/trace.go:171","msg":"trace[1317181676] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"179.894351ms","start":"2026-05-24T12:58:44.659069Z","end":"2026-05-24T12:58:44.838963Z","steps":["trace[1317181676] 'process raft request' (duration: 179.754878ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T12:58:44.839535Z","caller":"traceutil/trace.go:171","msg":"trace[537335256] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"185.986728ms","start":"2026-05-24T12:58:44.653517Z","end":"2026-05-24T12:58:44.839503Z","steps":["trace[537335256] 'process raft request' (duration: 184.996567ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T12:58:51.275033Z","caller":"traceutil/trace.go:171","msg":"trace[1133895203] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"131.674394ms","start":"2026-05-24T12:58:51.143307Z","end":"2026-05-24T12:58:51.274981Z","steps":["trace[1133895203] 'read index received' (duration: 130.507049ms)","trace[1133895203] 'applied index is now lower than readState.Index' (duration: 1.166265ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T12:58:51.275149Z","caller":"traceutil/trace.go:171","msg":"trace[1562688037] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"462.854723ms","start":"2026-05-24T12:58:50.812266Z","end":"2026-05-24T12:58:51.27512Z","steps":["trace[1562688037] 'process raft request' (duration: 461.943614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T12:58:51.275285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T12:58:50.812246Z","time spent":"462.967735ms","remote":"127.0.0.1:35310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T12:58:51.275346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.023741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-24T12:58:51.275438Z","caller":"traceutil/trace.go:171","msg":"trace[1857975941] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"132.171424ms","start":"2026-05-24T12:58:51.143254Z","end":"2026-05-24T12:58:51.275425Z","steps":["trace[1857975941] 'agreement among raft nodes before linearized reading' (duration: 131.876468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T12:59:05.995189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.314657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-cgwlh\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-24T12:59:05.995287Z","caller":"traceutil/trace.go:171","msg":"trace[130126376] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-cgwlh; range_end:; response_count:1; response_revision:796; }","duration":"219.472401ms","start":"2026-05-24T12:59:05.775795Z","end":"2026-05-24T12:59:05.995268Z","steps":["trace[130126376] 'range keys from in-memory index tree' (duration: 219.101164ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T12:59:40.311592Z","caller":"traceutil/trace.go:171","msg":"trace[1396752510] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"105.178148ms","start":"2026-05-24T12:59:40.206394Z","end":"2026-05-24T12:59:40.311572Z","steps":["trace[1396752510] 'process raft request' (duration: 105.062636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T12:59:40.572577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.477621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-24T12:59:40.572676Z","caller":"traceutil/trace.go:171","msg":"trace[656534862] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1013; }","duration":"219.619845ms","start":"2026-05-24T12:59:40.35303Z","end":"2026-05-24T12:59:40.57265Z","steps":["trace[656534862] 'count revisions from in-memory index tree' (duration: 219.40526ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:00:02.405731Z","caller":"traceutil/trace.go:171","msg":"trace[658418337] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"187.566608ms","start":"2026-05-24T13:00:02.218127Z","end":"2026-05-24T13:00:02.405694Z","steps":["trace[658418337] 'process raft request' (duration: 181.306992ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:00:03.282753Z","caller":"traceutil/trace.go:171","msg":"trace[961073068] linearizableReadLoop","detail":"{readStateIndex:1128; appliedIndex:1127; }","duration":"165.648817ms","start":"2026-05-24T13:00:03.117079Z","end":"2026-05-24T13:00:03.282728Z","steps":["trace[961073068] 'read index received' (duration: 165.455803ms)","trace[961073068] 'applied index is now lower than readState.Index' (duration: 188.044µs)"],"step_count":2} {"level":"info","ts":"2026-05-24T13:00:03.282802Z","caller":"traceutil/trace.go:171","msg":"trace[1798817607] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"273.082961ms","start":"2026-05-24T13:00:03.00969Z","end":"2026-05-24T13:00:03.282773Z","steps":["trace[1798817607] 'process raft request' (duration: 272.894677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:00:03.282946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.853401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-24T13:00:03.282983Z","caller":"traceutil/trace.go:171","msg":"trace[1590243494] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1088; }","duration":"165.911162ms","start":"2026-05-24T13:00:03.117063Z","end":"2026-05-24T13:00:03.282975Z","steps":["trace[1590243494] 'agreement among raft nodes before linearized reading' (duration: 165.764549ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:00:03.4004Z","caller":"traceutil/trace.go:171","msg":"trace[1825791744] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"111.2157ms","start":"2026-05-24T13:00:03.289156Z","end":"2026-05-24T13:00:03.400372Z","steps":["trace[1825791744] 'process raft request' (duration: 104.25051ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:00:04.538516Z","caller":"traceutil/trace.go:171","msg":"trace[172062105] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"115.211912ms","start":"2026-05-24T13:00:04.423278Z","end":"2026-05-24T13:00:04.53849Z","steps":["trace[172062105] 'process raft request' (duration: 115.003688ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:00:18.790263Z","caller":"traceutil/trace.go:171","msg":"trace[504209002] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"182.042858ms","start":"2026-05-24T13:00:18.608187Z","end":"2026-05-24T13:00:18.79023Z","steps":["trace[504209002] 'process raft request' (duration: 181.789483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:00:37.245444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.345028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-24T13:00:37.245565Z","caller":"traceutil/trace.go:171","msg":"trace[1589094238] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"284.298418ms","start":"2026-05-24T13:00:36.961243Z","end":"2026-05-24T13:00:37.245542Z","steps":["trace[1589094238] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-svcs-lock; req_size:419; } (duration: 282.020492ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:00:37.97468Z","caller":"traceutil/trace.go:171","msg":"trace[1975187100] linearizableReadLoop","detail":"{readStateIndex:1338; appliedIndex:1337; }","duration":"135.567641ms","start":"2026-05-24T13:00:37.839081Z","end":"2026-05-24T13:00:37.974648Z","steps":["trace[1975187100] 'read index received' (duration: 135.296086ms)","trace[1975187100] 'applied index is now lower than readState.Index' (duration: 270.505µs)"],"step_count":2} {"level":"info","ts":"2026-05-24T13:00:37.974726Z","caller":"traceutil/trace.go:171","msg":"trace[1685563242] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"154.242477ms","start":"2026-05-24T13:00:37.820422Z","end":"2026-05-24T13:00:37.974665Z","steps":["trace[1685563242] 'process raft request' (duration: 154.071014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:00:37.974979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.872707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-24T13:00:37.975054Z","caller":"traceutil/trace.go:171","msg":"trace[214982419] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1289; }","duration":"135.981599ms","start":"2026-05-24T13:00:37.839054Z","end":"2026-05-24T13:00:37.975036Z","steps":["trace[214982419] 'agreement among raft nodes before linearized reading' (duration: 135.719453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:00:47.601342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.69448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-24T13:00:47.601553Z","caller":"traceutil/trace.go:171","msg":"trace[728369240] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1354; }","duration":"177.948855ms","start":"2026-05-24T13:00:47.423591Z","end":"2026-05-24T13:00:47.60154Z","steps":["trace[728369240] 'count revisions from in-memory index tree' (duration: 177.570648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:00:47.602198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.003977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T13:00:47.602268Z","caller":"traceutil/trace.go:171","msg":"trace[883681105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1354; }","duration":"117.11943ms","start":"2026-05-24T13:00:47.485133Z","end":"2026-05-24T13:00:47.602253Z","steps":["trace[883681105] 'range keys from in-memory index tree' (duration: 116.400665ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:00:47.806584Z","caller":"traceutil/trace.go:171","msg":"trace[1067617071] linearizableReadLoop","detail":"{readStateIndex:1406; appliedIndex:1404; }","duration":"221.271307ms","start":"2026-05-24T13:00:47.585286Z","end":"2026-05-24T13:00:47.806557Z","steps":["trace[1067617071] 'read index received' (duration: 11.066843ms)","trace[1067617071] 'applied index is now lower than readState.Index' (duration: 210.203474ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T13:00:47.806762Z","caller":"traceutil/trace.go:171","msg":"trace[1733732191] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"295.149475ms","start":"2026-05-24T13:00:47.511598Z","end":"2026-05-24T13:00:47.806747Z","steps":["trace[1733732191] 'process raft request' (duration: 294.796638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:00:47.806983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.49822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T13:00:47.807086Z","caller":"traceutil/trace.go:171","msg":"trace[1191350526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1355; }","duration":"203.636542ms","start":"2026-05-24T13:00:47.603421Z","end":"2026-05-24T13:00:47.807058Z","steps":["trace[1191350526] 'agreement among raft nodes before linearized reading' (duration: 203.50408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:00:47.807259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.955702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-24T13:00:47.807345Z","caller":"traceutil/trace.go:171","msg":"trace[1734549180] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1355; }","duration":"222.088154ms","start":"2026-05-24T13:00:47.585236Z","end":"2026-05-24T13:00:47.807324Z","steps":["trace[1734549180] 'agreement among raft nodes before linearized reading' (duration: 221.830298ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T13:00:47.964165Z","caller":"traceutil/trace.go:171","msg":"trace[1571697239] linearizableReadLoop","detail":"{readStateIndex:1407; appliedIndex:1406; }","duration":"141.722455ms","start":"2026-05-24T13:00:47.822421Z","end":"2026-05-24T13:00:47.964144Z","steps":["trace[1571697239] 'read index received' (duration: 139.197114ms)","trace[1571697239] 'applied index is now lower than readState.Index' (duration: 2.524371ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T13:00:47.964314Z","caller":"traceutil/trace.go:171","msg":"trace[52566507] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"153.275237ms","start":"2026-05-24T13:00:47.811024Z","end":"2026-05-24T13:00:47.9643Z","steps":["trace[52566507] 'process raft request' (duration: 150.611763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T13:00:47.964343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.908858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-24T13:00:47.964374Z","caller":"traceutil/trace.go:171","msg":"trace[1200346285] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1356; }","duration":"141.976659ms","start":"2026-05-24T13:00:47.822389Z","end":"2026-05-24T13:00:47.964366Z","steps":["trace[1200346285] 'agreement among raft nodes before linearized reading' (duration: 141.862627ms)"],"step_count":1}