{"level":"warn","ts":"2026-04-16T11:44:01.890281Z","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-16T11:44:01.890386Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89: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.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89: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-16T11:44:01.890456Z","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-16T11:44:01.890464Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-04-16T11:44:01.890479Z","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-16T11:44:01.891201Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-04-16T11:44:01.8913Z","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.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89: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-16T11:44:01.899518Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.891129ms"} {"level":"info","ts":"2026-04-16T11:44:01.910976Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-04-16T11:44:01.911068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T11:44:01.911089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-04-16T11:44:01.911098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T11:44:01.911105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-04-16T11:44:01.911139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-04-16T11:44:01.928493Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T11:44:01.931879Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T11:44:01.934519Z","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-16T11:44:01.93872Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T11:44:01.938946Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T11:44:01.939304Z","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-16T11:44:01.940781Z","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-16T11:44:01.947451Z","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-16T11:44:01.941143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-04-16T11:44:01.951595Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-04-16T11:44:01.953949Z","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-16T11:44:01.954112Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-04-16T11:44:01.954162Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-04-16T11:44:01.955296Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T11:44:01.955344Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T11:44:02.511772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T11:44:02.511834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T11:44:02.511877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-04-16T11:44:02.511891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-04-16T11:44:02.511905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-04-16T11:44:02.511914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-04-16T11:44:02.511924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-04-16T11:44:02.514224Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T11:44:02.514277Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T11:44:02.514683Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T11:44:02.514927Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T11:44:02.515382Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T11:44:02.515403Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-16T11:44:02.515924Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T11:44:02.516246Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T11:44:02.516365Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T11:44:02.516861Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-16T11:44:02.528416Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"warn","ts":"2026-04-16T11:44:07.168613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.425906ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T11:44:07.168752Z","caller":"traceutil/trace.go:171","msg":"trace[1202486586] transaction","detail":"{read_only:false; response_revision:216; number_of_response:1; }","duration":"291.501964ms","start":"2026-04-16T11:44:06.877229Z","end":"2026-04-16T11:44:07.168731Z","steps":["trace[1202486586] 'process raft request' (duration: 19.708473ms)","trace[1202486586] 'compare' (duration: 270.268303ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:44:12.591283Z","caller":"traceutil/trace.go:171","msg":"trace[1711173127] linearizableReadLoop","detail":"{readStateIndex:316; appliedIndex:315; }","duration":"273.638419ms","start":"2026-04-16T11:44:12.317613Z","end":"2026-04-16T11:44:12.591251Z","steps":["trace[1711173127] 'read index received' (duration: 273.347172ms)","trace[1711173127] 'applied index is now lower than readState.Index' (duration: 287.217µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:44:12.591335Z","caller":"traceutil/trace.go:171","msg":"trace[1812372823] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"279.382599ms","start":"2026-04-16T11:44:12.311916Z","end":"2026-04-16T11:44:12.591299Z","steps":["trace[1812372823] 'process raft request' (duration: 279.147504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:12.591662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.948186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-16T11:44:12.591684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.817641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-16T11:44:12.59169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.170424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-04-16T11:44:12.591732Z","caller":"traceutil/trace.go:171","msg":"trace[765537576] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:304; }","duration":"105.905003ms","start":"2026-04-16T11:44:12.48581Z","end":"2026-04-16T11:44:12.591715Z","steps":["trace[765537576] 'agreement among raft nodes before linearized reading' (duration: 105.759ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:12.591737Z","caller":"traceutil/trace.go:171","msg":"trace[795319032] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:304; }","duration":"136.250886ms","start":"2026-04-16T11:44:12.455473Z","end":"2026-04-16T11:44:12.591724Z","steps":["trace[795319032] 'agreement among raft nodes before linearized reading' (duration: 136.102222ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:12.591752Z","caller":"traceutil/trace.go:171","msg":"trace[387368594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:304; }","duration":"274.10515ms","start":"2026-04-16T11:44:12.317601Z","end":"2026-04-16T11:44:12.591706Z","steps":["trace[387368594] 'agreement among raft nodes before linearized reading' (duration: 273.898745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:12.591677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.569013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-04-16T11:44:12.59182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.581908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-04-16T11:44:12.591841Z","caller":"traceutil/trace.go:171","msg":"trace[1844871758] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:304; }","duration":"234.832859ms","start":"2026-04-16T11:44:12.356987Z","end":"2026-04-16T11:44:12.59182Z","steps":["trace[1844871758] 'agreement among raft nodes before linearized reading' (duration: 234.534502ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:12.591883Z","caller":"traceutil/trace.go:171","msg":"trace[256044184] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:304; }","duration":"186.68489ms","start":"2026-04-16T11:44:12.405182Z","end":"2026-04-16T11:44:12.591867Z","steps":["trace[256044184] 'agreement among raft nodes before linearized reading' (duration: 186.510176ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:12.912505Z","caller":"traceutil/trace.go:171","msg":"trace[6383568] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"315.937313ms","start":"2026-04-16T11:44:12.596536Z","end":"2026-04-16T11:44:12.912473Z","steps":["trace[6383568] 'process raft request' (duration: 276.377395ms)","trace[6383568] 'compare' (duration: 39.429315ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T11:44:12.913415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:12.596513Z","time spent":"316.072147ms","remote":"127.0.0.1:42606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T11:44:13.163332Z","caller":"traceutil/trace.go:171","msg":"trace[853198458] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"566.343607ms","start":"2026-04-16T11:44:12.596945Z","end":"2026-04-16T11:44:13.163289Z","steps":["trace[853198458] 'process raft request' (duration: 563.397965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:13.163555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:12.596928Z","time spent":"566.532232ms","remote":"127.0.0.1:42606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T11:44:13.163635Z","caller":"traceutil/trace.go:171","msg":"trace[1918874398] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"563.267752ms","start":"2026-04-16T11:44:12.600343Z","end":"2026-04-16T11:44:13.16361Z","steps":["trace[1918874398] 'process raft request' (duration: 563.028606ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:13.163692Z","caller":"traceutil/trace.go:171","msg":"trace[1019621984] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"563.727783ms","start":"2026-04-16T11:44:12.599944Z","end":"2026-04-16T11:44:13.163672Z","steps":["trace[1019621984] 'process raft request' (duration: 563.096538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:13.163767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:12.600322Z","time spent":"563.386045ms","remote":"127.0.0.1:42832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T11:44:13.163788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:12.599933Z","time spent":"563.800404ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":156,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T11:44:13.164201Z","caller":"traceutil/trace.go:171","msg":"trace[1125562393] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"562.631516ms","start":"2026-04-16T11:44:12.601552Z","end":"2026-04-16T11:44:13.164184Z","steps":["trace[1125562393] 'process raft request' (duration: 561.891569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:13.164296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:12.601542Z","time spent":"562.698568ms","remote":"127.0.0.1:42698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T11:44:13.165409Z","caller":"traceutil/trace.go:171","msg":"trace[1354276720] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"563.028306ms","start":"2026-04-16T11:44:12.602353Z","end":"2026-04-16T11:44:13.165382Z","steps":["trace[1354276720] 'process raft request' (duration: 561.129749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:13.165543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:12.602346Z","time spent":"563.127378ms","remote":"127.0.0.1:42698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T11:44:13.165543Z","caller":"traceutil/trace.go:171","msg":"trace[1365502807] linearizableReadLoop","detail":"{readStateIndex:324; appliedIndex:316; }","duration":"560.807541ms","start":"2026-04-16T11:44:12.604713Z","end":"2026-04-16T11:44:13.16552Z","steps":["trace[1365502807] 'read index received' (duration: 268.365449ms)","trace[1365502807] 'applied index is now lower than readState.Index' (duration: 292.440922ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:44:13.165775Z","caller":"traceutil/trace.go:171","msg":"trace[410524985] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"563.346063ms","start":"2026-04-16T11:44:12.602421Z","end":"2026-04-16T11:44:13.165767Z","steps":["trace[410524985] 'process raft request' (duration: 562.77584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:13.165828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:12.602417Z","time spent":"563.384174ms","remote":"127.0.0.1:42592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2263,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T11:44:13.16582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.098388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-04-16T11:44:13.165896Z","caller":"traceutil/trace.go:171","msg":"trace[2022162567] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:313; }","duration":"561.16536ms","start":"2026-04-16T11:44:12.604691Z","end":"2026-04-16T11:44:13.165856Z","steps":["trace[2022162567] 'agreement among raft nodes before linearized reading' (duration: 560.985316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:13.165905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.432458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-16T11:44:13.165681Z","caller":"traceutil/trace.go:171","msg":"trace[590543067] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"563.20313ms","start":"2026-04-16T11:44:12.602455Z","end":"2026-04-16T11:44:13.165658Z","steps":["trace[590543067] 'process raft request' (duration: 562.906912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:13.165941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:12.604612Z","time spent":"561.317395ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":219,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-04-16T11:44:13.165998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:12.602451Z","time spent":"563.527388ms","remote":"127.0.0.1:42698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T11:44:13.166134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.452789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-04-16T11:44:13.166185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.677246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-16T11:44:13.166183Z","caller":"traceutil/trace.go:171","msg":"trace[713015891] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"560.011312ms","start":"2026-04-16T11:44:12.606156Z","end":"2026-04-16T11:44:13.166167Z","steps":["trace[713015891] 'process raft request' (duration: 559.280604ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:13.166195Z","caller":"traceutil/trace.go:171","msg":"trace[116748239] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:313; }","duration":"260.568841ms","start":"2026-04-16T11:44:12.905612Z","end":"2026-04-16T11:44:13.166181Z","steps":["trace[116748239] 'agreement among raft nodes before linearized reading' (duration: 260.464379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:13.166244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.908828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-04-16T11:44:13.166249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:12.606137Z","time spent":"560.074624ms","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4188,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T11:44:13.166266Z","caller":"traceutil/trace.go:171","msg":"trace[717039319] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:313; }","duration":"360.97963ms","start":"2026-04-16T11:44:12.805281Z","end":"2026-04-16T11:44:13.166261Z","steps":["trace[717039319] 'agreement among raft nodes before linearized reading' (duration: 360.944559ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:13.166286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:12.805256Z","time spent":"361.025461ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-04-16T11:44:13.166288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.103758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-04-16T11:44:13.166353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.87972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-04-16T11:44:13.16635Z","caller":"traceutil/trace.go:171","msg":"trace[505639037] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:313; }","duration":"311.16473ms","start":"2026-04-16T11:44:12.855165Z","end":"2026-04-16T11:44:13.16633Z","steps":["trace[505639037] 'agreement among raft nodes before linearized reading' (duration: 311.059747ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:13.166374Z","caller":"traceutil/trace.go:171","msg":"trace[909634276] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:313; }","duration":"461.937972ms","start":"2026-04-16T11:44:12.704431Z","end":"2026-04-16T11:44:13.166369Z","steps":["trace[909634276] 'agreement among raft nodes before linearized reading' (duration: 461.903481ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:13.166207Z","caller":"traceutil/trace.go:171","msg":"trace[1700627022] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:313; }","duration":"410.753399ms","start":"2026-04-16T11:44:12.755446Z","end":"2026-04-16T11:44:13.1662Z","steps":["trace[1700627022] 'agreement among raft nodes before linearized reading' (duration: 410.712018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:13.166404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:12.855101Z","time spent":"311.282183ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":227,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-04-16T11:44:13.16644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:12.755386Z","time spent":"411.038695ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-04-16T11:44:13.166392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:12.704401Z","time spent":"461.987172ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":221,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"info","ts":"2026-04-16T11:44:13.165964Z","caller":"traceutil/trace.go:171","msg":"trace[2144253906] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:313; }","duration":"510.491069ms","start":"2026-04-16T11:44:12.655455Z","end":"2026-04-16T11:44:13.165946Z","steps":["trace[2144253906] 'agreement among raft nodes before linearized reading' (duration: 510.394457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:13.166645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:12.655383Z","time spent":"511.241428ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"info","ts":"2026-04-16T11:44:31.445993Z","caller":"traceutil/trace.go:171","msg":"trace[1833135438] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"108.378772ms","start":"2026-04-16T11:44:31.337586Z","end":"2026-04-16T11:44:31.445964Z","steps":["trace[1833135438] 'process raft request' (duration: 108.291301ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:31.446047Z","caller":"traceutil/trace.go:171","msg":"trace[1068294789] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"112.224156ms","start":"2026-04-16T11:44:31.33377Z","end":"2026-04-16T11:44:31.445994Z","steps":["trace[1068294789] 'process raft request' (duration: 52.141702ms)","trace[1068294789] 'compare' (duration: 59.719786ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:44:31.815228Z","caller":"traceutil/trace.go:171","msg":"trace[306650842] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"124.408681ms","start":"2026-04-16T11:44:31.690783Z","end":"2026-04-16T11:44:31.815192Z","steps":["trace[306650842] 'process raft request' (duration: 121.487177ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:31.815641Z","caller":"traceutil/trace.go:171","msg":"trace[1498178199] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:528; }","duration":"118.952892ms","start":"2026-04-16T11:44:31.696674Z","end":"2026-04-16T11:44:31.815627Z","steps":["trace[1498178199] 'read index received' (duration: 115.61972ms)","trace[1498178199] 'applied index is now lower than readState.Index' (duration: 3.332222ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T11:44:31.815763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.279762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:31.815907Z","caller":"traceutil/trace.go:171","msg":"trace[644170630] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:509; }","duration":"126.486167ms","start":"2026-04-16T11:44:31.68941Z","end":"2026-04-16T11:44:31.815896Z","steps":["trace[644170630] 'agreement among raft nodes before linearized reading' (duration: 126.273112ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:31.815856Z","caller":"traceutil/trace.go:171","msg":"trace[115026137] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"124.888052ms","start":"2026-04-16T11:44:31.690957Z","end":"2026-04-16T11:44:31.815845Z","steps":["trace[115026137] 'process raft request' (duration: 124.175836ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:31.824164Z","caller":"traceutil/trace.go:171","msg":"trace[370422353] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"110.004858ms","start":"2026-04-16T11:44:31.714136Z","end":"2026-04-16T11:44:31.824141Z","steps":["trace[370422353] 'process raft request' (duration: 109.575359ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:31.824681Z","caller":"traceutil/trace.go:171","msg":"trace[1903592793] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"123.316407ms","start":"2026-04-16T11:44:31.701061Z","end":"2026-04-16T11:44:31.824377Z","steps":["trace[1903592793] 'process raft request' (duration: 122.473749ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:32.272286Z","caller":"traceutil/trace.go:171","msg":"trace[1685309575] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"152.302338ms","start":"2026-04-16T11:44:32.119947Z","end":"2026-04-16T11:44:32.27225Z","steps":["trace[1685309575] 'process raft request' (duration: 83.110703ms)","trace[1685309575] 'compare' (duration: 68.9763ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:44:32.273553Z","caller":"traceutil/trace.go:171","msg":"trace[877587556] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"145.274467ms","start":"2026-04-16T11:44:32.128257Z","end":"2026-04-16T11:44:32.273532Z","steps":["trace[877587556] 'process raft request' (duration: 145.136814ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:37.789128Z","caller":"traceutil/trace.go:171","msg":"trace[1874827344] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:593; }","duration":"100.144133ms","start":"2026-04-16T11:44:37.68895Z","end":"2026-04-16T11:44:37.789094Z","steps":["trace[1874827344] 'read index received' (duration: 57.044727ms)","trace[1874827344] 'applied index is now lower than readState.Index' (duration: 43.098046ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:44:37.78925Z","caller":"traceutil/trace.go:171","msg":"trace[875536042] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"169.516931ms","start":"2026-04-16T11:44:37.619669Z","end":"2026-04-16T11:44:37.789186Z","steps":["trace[875536042] 'process raft request' (duration: 126.403275ms)","trace[875536042] 'compare' (duration: 42.723827ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T11:44:37.789348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.47418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:37.789427Z","caller":"traceutil/trace.go:171","msg":"trace[360197919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:572; }","duration":"100.621113ms","start":"2026-04-16T11:44:37.688783Z","end":"2026-04-16T11:44:37.789404Z","steps":["trace[360197919] 'agreement among raft nodes before linearized reading' (duration: 100.45966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:38.140943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.223185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T11:44:38.141267Z","caller":"traceutil/trace.go:171","msg":"trace[1417785026] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"311.664149ms","start":"2026-04-16T11:44:37.829574Z","end":"2026-04-16T11:44:38.141238Z","steps":["trace[1417785026] 'process raft request' (duration: 94.021385ms)","trace[1417785026] 'compare' (duration: 217.033601ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T11:44:38.141394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:37.829557Z","time spent":"311.76539ms","remote":"127.0.0.1:42528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T11:44:38.141539Z","caller":"traceutil/trace.go:171","msg":"trace[392083131] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"291.902223ms","start":"2026-04-16T11:44:37.849612Z","end":"2026-04-16T11:44:38.141514Z","steps":["trace[392083131] 'read index received' (duration: 73.84664ms)","trace[392083131] 'applied index is now lower than readState.Index' (duration: 218.054243ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T11:44:38.141707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.086637ms","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-16T11:44:38.141739Z","caller":"traceutil/trace.go:171","msg":"trace[1977318973] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:574; }","duration":"292.150869ms","start":"2026-04-16T11:44:37.849577Z","end":"2026-04-16T11:44:38.141728Z","steps":["trace[1977318973] 'agreement among raft nodes before linearized reading' (duration: 292.001666ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:38.456818Z","caller":"traceutil/trace.go:171","msg":"trace[2055079285] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"206.539153ms","start":"2026-04-16T11:44:38.250254Z","end":"2026-04-16T11:44:38.456793Z","steps":["trace[2055079285] 'process raft request' (duration: 205.553133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:39.190401Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095241506716618,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T11:44:39.214563Z","caller":"traceutil/trace.go:171","msg":"trace[670650526] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"804.706183ms","start":"2026-04-16T11:44:38.409829Z","end":"2026-04-16T11:44:39.214535Z","steps":["trace[670650526] 'process raft request' (duration: 804.507949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:39.214708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:38.409807Z","time spent":"804.827805ms","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4538,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T11:44:39.225748Z","caller":"traceutil/trace.go:171","msg":"trace[1090432113] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:599; }","duration":"536.243445ms","start":"2026-04-16T11:44:38.689476Z","end":"2026-04-16T11:44:39.22572Z","steps":["trace[1090432113] 'read index received' (duration: 525.562954ms)","trace[1090432113] 'applied index is now lower than readState.Index' (duration: 10.679671ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:44:39.225816Z","caller":"traceutil/trace.go:171","msg":"trace[1394731737] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"709.575065ms","start":"2026-04-16T11:44:38.516223Z","end":"2026-04-16T11:44:39.225799Z","steps":["trace[1394731737] 'process raft request' (duration: 709.464613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:39.225899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.421349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T11:44:39.225933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:38.516191Z","time spent":"709.665846ms","remote":"127.0.0.1:42854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T11:44:39.225947Z","caller":"traceutil/trace.go:171","msg":"trace[2040917666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"536.47578ms","start":"2026-04-16T11:44:38.689454Z","end":"2026-04-16T11:44:39.225929Z","steps":["trace[2040917666] 'agreement among raft nodes before linearized reading' (duration: 536.398368ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:39.225956Z","caller":"traceutil/trace.go:171","msg":"trace[467742610] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"759.343705ms","start":"2026-04-16T11:44:38.466574Z","end":"2026-04-16T11:44:39.225918Z","steps":["trace[467742610] 'process raft request' (duration: 758.996158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:39.226054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.131059ms","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-16T11:44:39.226089Z","caller":"traceutil/trace.go:171","msg":"trace[1678811669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:579; }","duration":"164.219631ms","start":"2026-04-16T11:44:39.061861Z","end":"2026-04-16T11:44:39.226081Z","steps":["trace[1678811669] 'agreement among raft nodes before linearized reading' (duration: 164.108829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:39.226061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:38.689369Z","time spent":"536.655753ms","remote":"127.0.0.1:42346","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-16T11:44:39.226124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:38.466541Z","time spent":"759.495307ms","remote":"127.0.0.1:42606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T11:44:55.444827Z","caller":"traceutil/trace.go:171","msg":"trace[856313558] linearizableReadLoop","detail":"{readStateIndex:768; appliedIndex:767; }","duration":"132.241711ms","start":"2026-04-16T11:44:55.312569Z","end":"2026-04-16T11:44:55.444811Z","steps":["trace[856313558] 'read index received' (duration: 132.063877ms)","trace[856313558] 'applied index is now lower than readState.Index' (duration: 177.154µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:44:55.445069Z","caller":"traceutil/trace.go:171","msg":"trace[2119483972] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"293.240465ms","start":"2026-04-16T11:44:55.15182Z","end":"2026-04-16T11:44:55.44506Z","steps":["trace[2119483972] 'process raft request' (duration: 292.878379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:55.445253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.68364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-16T11:44:55.445276Z","caller":"traceutil/trace.go:171","msg":"trace[1743971277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:742; }","duration":"132.773341ms","start":"2026-04-16T11:44:55.312495Z","end":"2026-04-16T11:44:55.445268Z","steps":["trace[1743971277] 'agreement among raft nodes before linearized reading' (duration: 132.68213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:55.445397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.960436ms","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-16T11:44:55.445413Z","caller":"traceutil/trace.go:171","msg":"trace[1378220447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:742; }","duration":"131.991336ms","start":"2026-04-16T11:44:55.313417Z","end":"2026-04-16T11:44:55.445408Z","steps":["trace[1378220447] 'agreement among raft nodes before linearized reading' (duration: 131.940755ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:45:59.991455Z","caller":"traceutil/trace.go:171","msg":"trace[399720013] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"108.40156ms","start":"2026-04-16T11:45:59.882994Z","end":"2026-04-16T11:45:59.991396Z","steps":["trace[399720013] 'process raft request' (duration: 108.211295ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:46:00.152858Z","caller":"traceutil/trace.go:171","msg":"trace[1240121778] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"269.449876ms","start":"2026-04-16T11:45:59.883381Z","end":"2026-04-16T11:46:00.152831Z","steps":["trace[1240121778] 'process raft request' (duration: 207.070586ms)","trace[1240121778] 'compare' (duration: 62.215175ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:46:00.15321Z","caller":"traceutil/trace.go:171","msg":"trace[740167604] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"269.63522ms","start":"2026-04-16T11:45:59.883527Z","end":"2026-04-16T11:46:00.153162Z","steps":["trace[740167604] 'process raft request' (duration: 269.264841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:46:25.207968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.825898ms","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-04-16T11:46:25.208048Z","caller":"traceutil/trace.go:171","msg":"trace[1753391895] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1137; }","duration":"128.93969ms","start":"2026-04-16T11:46:25.079085Z","end":"2026-04-16T11:46:25.208025Z","steps":["trace[1753391895] 'range keys from in-memory index tree' (duration: 128.618793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:46:25.208069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.111866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:46:25.208202Z","caller":"traceutil/trace.go:171","msg":"trace[1954729179] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1137; }","duration":"195.307541ms","start":"2026-04-16T11:46:25.012863Z","end":"2026-04-16T11:46:25.20817Z","steps":["trace[1954729179] 'count revisions from in-memory index tree' (duration: 194.999883ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:50:39.773866Z","caller":"traceutil/trace.go:171","msg":"trace[1191731351] transaction","detail":"{read_only:false; response_revision:1975; number_of_response:1; }","duration":"133.568454ms","start":"2026-04-16T11:50:39.64027Z","end":"2026-04-16T11:50:39.773838Z","steps":["trace[1191731351] 'process raft request' (duration: 133.288155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:50:40.061341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.460928ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T11:50:40.061461Z","caller":"traceutil/trace.go:171","msg":"trace[2067762183] transaction","detail":"{read_only:false; response_revision:1976; number_of_response:1; }","duration":"283.58391ms","start":"2026-04-16T11:50:39.777857Z","end":"2026-04-16T11:50:40.061441Z","steps":["trace[2067762183] 'process raft request' (duration: 117.923476ms)","trace[2067762183] 'compare' (duration: 165.318583ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:51:08.05341Z","caller":"traceutil/trace.go:171","msg":"trace[133343258] transaction","detail":"{read_only:false; response_revision:2068; number_of_response:1; }","duration":"125.871793ms","start":"2026-04-16T11:51:07.927501Z","end":"2026-04-16T11:51:08.053373Z","steps":["trace[133343258] 'process raft request' (duration: 125.645215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:51:09.036936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.653398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T11:51:09.037065Z","caller":"traceutil/trace.go:171","msg":"trace[693711029] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:2072; }","duration":"236.857695ms","start":"2026-04-16T11:51:08.800182Z","end":"2026-04-16T11:51:09.03704Z","steps":["trace[693711029] 'count revisions from in-memory index tree' (duration: 236.545305ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:51:21.94555Z","caller":"traceutil/trace.go:171","msg":"trace[2098065046] linearizableReadLoop","detail":"{readStateIndex:2270; appliedIndex:2269; }","duration":"141.07467ms","start":"2026-04-16T11:51:21.804455Z","end":"2026-04-16T11:51:21.94553Z","steps":["trace[2098065046] 'read index received' (duration: 140.933406ms)","trace[2098065046] 'applied index is now lower than readState.Index' (duration: 140.324µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:51:21.94561Z","caller":"traceutil/trace.go:171","msg":"trace[1622220900] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"179.289434ms","start":"2026-04-16T11:51:21.766302Z","end":"2026-04-16T11:51:21.945592Z","steps":["trace[1622220900] 'process raft request' (duration: 179.095868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:51:21.945683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.212245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:51:21.94571Z","caller":"traceutil/trace.go:171","msg":"trace[420040033] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:2163; }","duration":"141.258366ms","start":"2026-04-16T11:51:21.804444Z","end":"2026-04-16T11:51:21.945703Z","steps":["trace[420040033] 'agreement among raft nodes before linearized reading' (duration: 141.165203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:51:36.956709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.655386ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T11:51:36.956798Z","caller":"traceutil/trace.go:171","msg":"trace[1020291571] linearizableReadLoop","detail":"{readStateIndex:2326; appliedIndex:2325; }","duration":"267.616371ms","start":"2026-04-16T11:51:36.689169Z","end":"2026-04-16T11:51:36.956785Z","steps":["trace[1020291571] 'read index received' (duration: 45.701µs)","trace[1020291571] 'applied index is now lower than readState.Index' (duration: 267.56957ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:51:36.956874Z","caller":"traceutil/trace.go:171","msg":"trace[1866969941] transaction","detail":"{read_only:false; response_revision:2216; number_of_response:1; }","duration":"296.904377ms","start":"2026-04-16T11:51:36.659962Z","end":"2026-04-16T11:51:36.956867Z","steps":["trace[1866969941] 'process raft request' (duration: 15.019674ms)","trace[1866969941] 'compare' (duration: 281.545913ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T11:51:36.956982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.820887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:51:36.957007Z","caller":"traceutil/trace.go:171","msg":"trace[62644460] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2216; }","duration":"267.877289ms","start":"2026-04-16T11:51:36.689124Z","end":"2026-04-16T11:51:36.957001Z","steps":["trace[62644460] 'agreement among raft nodes before linearized reading' (duration: 267.833907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:51:36.957181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.215616ms","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-16T11:51:36.957197Z","caller":"traceutil/trace.go:171","msg":"trace[859217192] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2216; }","duration":"132.276767ms","start":"2026-04-16T11:51:36.824916Z","end":"2026-04-16T11:51:36.957192Z","steps":["trace[859217192] 'agreement among raft nodes before linearized reading' (duration: 132.201405ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:51:37.139099Z","caller":"traceutil/trace.go:171","msg":"trace[371829567] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"177.319693ms","start":"2026-04-16T11:51:36.961734Z","end":"2026-04-16T11:51:37.139054Z","steps":["trace[371829567] 'process raft request' (duration: 104.57029ms)","trace[371829567] 'compare' (duration: 72.552597ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T11:51:43.363276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.691428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-16T11:51:43.363533Z","caller":"traceutil/trace.go:171","msg":"trace[1819834984] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2284; }","duration":"201.011878ms","start":"2026-04-16T11:51:43.162499Z","end":"2026-04-16T11:51:43.363511Z","steps":["trace[1819834984] 'range keys from in-memory index tree' (duration: 200.203643ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:51:43.964475Z","caller":"traceutil/trace.go:171","msg":"trace[2080912345] transaction","detail":"{read_only:false; response_revision:2287; number_of_response:1; }","duration":"210.658746ms","start":"2026-04-16T11:51:43.753793Z","end":"2026-04-16T11:51:43.964451Z","steps":["trace[2080912345] 'process raft request' (duration: 210.543243ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:51:57.566033Z","caller":"traceutil/trace.go:171","msg":"trace[1476608845] linearizableReadLoop","detail":"{readStateIndex:2504; appliedIndex:2503; }","duration":"293.531573ms","start":"2026-04-16T11:51:57.27248Z","end":"2026-04-16T11:51:57.566012Z","steps":["trace[1476608845] 'read index received' (duration: 293.350928ms)","trace[1476608845] 'applied index is now lower than readState.Index' (duration: 179.455µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T11:51:57.566457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.949667ms","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:530"} {"level":"info","ts":"2026-04-16T11:51:57.56655Z","caller":"traceutil/trace.go:171","msg":"trace[1936855527] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2390; }","duration":"294.08182ms","start":"2026-04-16T11:51:57.272449Z","end":"2026-04-16T11:51:57.566531Z","steps":["trace[1936855527] 'agreement among raft nodes before linearized reading' (duration: 293.842493ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:51:57.566416Z","caller":"traceutil/trace.go:171","msg":"trace[1715023288] transaction","detail":"{read_only:false; response_revision:2390; number_of_response:1; }","duration":"445.12335ms","start":"2026-04-16T11:51:57.121259Z","end":"2026-04-16T11:51:57.566382Z","steps":["trace[1715023288] 'process raft request' (duration: 444.593784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:51:57.566682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.512717ms","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-16T11:51:57.566775Z","caller":"traceutil/trace.go:171","msg":"trace[306774867] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2390; }","duration":"104.651731ms","start":"2026-04-16T11:51:57.462111Z","end":"2026-04-16T11:51:57.566763Z","steps":["trace[306774867] 'agreement among raft nodes before linearized reading' (duration: 104.28435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:51:57.566793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:51:57.121243Z","time spent":"445.481891ms","remote":"127.0.0.1:42606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":40,"request content":"compare: success:> failure: >"}