{"level":"warn","ts":"2026-04-09T05:53:23.388582Z","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-09T05:53:23.388684Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.87:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.87:2380","--initial-cluster=instance=https://199.19.213.87:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.87:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.87: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-09T05:53:23.388755Z","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-09T05:53:23.38877Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.87:2380"]} {"level":"info","ts":"2026-04-09T05:53:23.388791Z","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-09T05:53:23.389583Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.87:2379"]} {"level":"info","ts":"2026-04-09T05:53:23.389673Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.87:2380"],"listen-peer-urls":["https://199.19.213.87:2380"],"advertise-client-urls":["https://199.19.213.87:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.87:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.87: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-09T05:53:23.39445Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.526023ms"} {"level":"info","ts":"2026-04-09T05:53:23.400974Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"bc3cb673a921c717","cluster-id":"f315f77360ac6549"} {"level":"info","ts":"2026-04-09T05:53:23.401031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 switched to configuration voters=()"} {"level":"info","ts":"2026-04-09T05:53:23.401054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 became follower at term 0"} {"level":"info","ts":"2026-04-09T05:53:23.401069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft bc3cb673a921c717 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-09T05:53:23.401083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 became follower at term 1"} {"level":"info","ts":"2026-04-09T05:53:23.40113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 switched to configuration voters=(13563916785608148759)"} {"level":"warn","ts":"2026-04-09T05:53:23.410574Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-09T05:53:23.414989Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-09T05:53:23.416082Z","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-09T05:53:23.41815Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"bc3cb673a921c717","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-09T05:53:23.418977Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"bc3cb673a921c717","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-09T05:53:23.41911Z","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-09T05:53:23.419168Z","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-09T05:53:23.419178Z","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-09T05:53:23.421559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 switched to configuration voters=(13563916785608148759)"} {"level":"info","ts":"2026-04-09T05:53:23.422424Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f315f77360ac6549","local-member-id":"bc3cb673a921c717","added-peer-id":"bc3cb673a921c717","added-peer-peer-urls":["https://199.19.213.87:2380"]} {"level":"info","ts":"2026-04-09T05:53:23.423278Z","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-09T05:53:23.423472Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"bc3cb673a921c717","initial-advertise-peer-urls":["https://199.19.213.87:2380"],"listen-peer-urls":["https://199.19.213.87:2380"],"advertise-client-urls":["https://199.19.213.87:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.87:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-09T05:53:23.423501Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-09T05:53:23.42402Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.87:2380"} {"level":"info","ts":"2026-04-09T05:53:23.424027Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.87:2380"} {"level":"info","ts":"2026-04-09T05:53:24.001983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 is starting a new election at term 1"} {"level":"info","ts":"2026-04-09T05:53:24.002053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-09T05:53:24.002136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 received MsgPreVoteResp from bc3cb673a921c717 at term 1"} {"level":"info","ts":"2026-04-09T05:53:24.002163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 became candidate at term 2"} {"level":"info","ts":"2026-04-09T05:53:24.002201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 received MsgVoteResp from bc3cb673a921c717 at term 2"} {"level":"info","ts":"2026-04-09T05:53:24.002244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 became leader at term 2"} {"level":"info","ts":"2026-04-09T05:53:24.002269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: bc3cb673a921c717 elected leader bc3cb673a921c717 at term 2"} {"level":"info","ts":"2026-04-09T05:53:24.003465Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T05:53:24.003606Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T05:53:24.003684Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"bc3cb673a921c717","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.87:2379]}","request-path":"/0/members/bc3cb673a921c717/attributes","cluster-id":"f315f77360ac6549","publish-timeout":"7s"} {"level":"info","ts":"2026-04-09T05:53:24.003958Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T05:53:24.004184Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-09T05:53:24.004258Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-09T05:53:24.004917Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f315f77360ac6549","local-member-id":"bc3cb673a921c717","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T05:53:24.004988Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T05:53:24.006665Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.87:2379"} {"level":"info","ts":"2026-04-09T05:53:24.011429Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-09T05:53:24.0178Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T05:53:29.821177Z","caller":"traceutil/trace.go:171","msg":"trace[2115045317] linearizableReadLoop","detail":"{readStateIndex:319; appliedIndex:318; }","duration":"110.336857ms","start":"2026-04-09T05:53:29.710814Z","end":"2026-04-09T05:53:29.821151Z","steps":["trace[2115045317] 'read index received' (duration: 110.178256ms)","trace[2115045317] 'applied index is now lower than readState.Index' (duration: 157.741µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:53:29.82143Z","caller":"traceutil/trace.go:171","msg":"trace[29849616] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"152.795569ms","start":"2026-04-09T05:53:29.668623Z","end":"2026-04-09T05:53:29.821419Z","steps":["trace[29849616] 'process raft request' (duration: 152.426107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:53:29.821632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.827139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-04-09T05:53:29.821708Z","caller":"traceutil/trace.go:171","msg":"trace[2113313134] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:310; }","duration":"110.956249ms","start":"2026-04-09T05:53:29.71074Z","end":"2026-04-09T05:53:29.821697Z","steps":["trace[2113313134] 'agreement among raft nodes before linearized reading' (duration: 110.841199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:53:29.821852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.012176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:53:29.821884Z","caller":"traceutil/trace.go:171","msg":"trace[1929776529] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:310; }","duration":"108.064116ms","start":"2026-04-09T05:53:29.713812Z","end":"2026-04-09T05:53:29.821876Z","steps":["trace[1929776529] 'agreement among raft nodes before linearized reading' (duration: 108.019776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:53:30.051773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.48749ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-09T05:53:30.051914Z","caller":"traceutil/trace.go:171","msg":"trace[1081297633] linearizableReadLoop","detail":"{readStateIndex:320; appliedIndex:319; }","duration":"224.204509ms","start":"2026-04-09T05:53:29.827692Z","end":"2026-04-09T05:53:30.051897Z","steps":["trace[1081297633] 'read index received' (duration: 39.080026ms)","trace[1081297633] 'applied index is now lower than readState.Index' (duration: 185.123213ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:53:30.052072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.313981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:53:30.052022Z","caller":"traceutil/trace.go:171","msg":"trace[1256373815] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"226.18296ms","start":"2026-04-09T05:53:29.825752Z","end":"2026-04-09T05:53:30.051935Z","steps":["trace[1256373815] 'process raft request' (duration: 40.890755ms)","trace[1256373815] 'compare' (duration: 184.344479ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:53:30.052113Z","caller":"traceutil/trace.go:171","msg":"trace[1895206470] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/statefulset-controller; range_end:; response_count:0; response_revision:311; }","duration":"224.426682ms","start":"2026-04-09T05:53:29.827676Z","end":"2026-04-09T05:53:30.052102Z","steps":["trace[1895206470] 'agreement among raft nodes before linearized reading' (duration: 224.283922ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:53:30.083639Z","caller":"traceutil/trace.go:171","msg":"trace[735738017] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"253.857701ms","start":"2026-04-09T05:53:29.82976Z","end":"2026-04-09T05:53:30.083618Z","steps":["trace[735738017] 'process raft request' (duration: 253.67596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:53:30.496581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.310748ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T05:53:30.496695Z","caller":"traceutil/trace.go:171","msg":"trace[24901910] linearizableReadLoop","detail":"{readStateIndex:324; appliedIndex:323; }","duration":"384.91724ms","start":"2026-04-09T05:53:30.111756Z","end":"2026-04-09T05:53:30.496674Z","steps":["trace[24901910] 'read index received' (duration: 252.38592ms)","trace[24901910] 'applied index is now lower than readState.Index' (duration: 132.52989ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:53:30.496765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.00433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-09T05:53:30.496786Z","caller":"traceutil/trace.go:171","msg":"trace[534440568] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:315; }","duration":"385.05541ms","start":"2026-04-09T05:53:30.111722Z","end":"2026-04-09T05:53:30.496778Z","steps":["trace[534440568] 'agreement among raft nodes before linearized reading' (duration: 385.01125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:53:30.496809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:53:30.11171Z","time spent":"385.09433ms","remote":"127.0.0.1:46782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"info","ts":"2026-04-09T05:53:30.496807Z","caller":"traceutil/trace.go:171","msg":"trace[780984542] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"390.598576ms","start":"2026-04-09T05:53:30.106155Z","end":"2026-04-09T05:53:30.496754Z","steps":["trace[780984542] 'process raft request' (duration: 257.965256ms)","trace[780984542] 'compare' (duration: 132.140378ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:53:30.497055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:53:30.106141Z","time spent":"390.773267ms","remote":"127.0.0.1:46846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T05:53:30.524271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.153125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-09T05:53:30.524312Z","caller":"traceutil/trace.go:171","msg":"trace[499975412] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"408.524192ms","start":"2026-04-09T05:53:30.11575Z","end":"2026-04-09T05:53:30.524274Z","steps":["trace[499975412] 'process raft request' (duration: 408.13536ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:53:30.524385Z","caller":"traceutil/trace.go:171","msg":"trace[1616308489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:316; }","duration":"409.315025ms","start":"2026-04-09T05:53:30.115046Z","end":"2026-04-09T05:53:30.524361Z","steps":["trace[1616308489] 'agreement among raft nodes before linearized reading' (duration: 409.068934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:53:30.524437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:53:30.115027Z","time spent":"409.399115ms","remote":"127.0.0.1:46846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T05:53:30.524431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.271725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-04-09T05:53:30.524511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:53:30.115735Z","time spent":"408.682062ms","remote":"127.0.0.1:46756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4645,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T05:53:30.524522Z","caller":"traceutil/trace.go:171","msg":"trace[1530237697] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:316; }","duration":"306.422896ms","start":"2026-04-09T05:53:30.218078Z","end":"2026-04-09T05:53:30.524501Z","steps":["trace[1530237697] 'agreement among raft nodes before linearized reading' (duration: 306.209865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:53:30.524562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:53:30.218037Z","time spent":"306.517916ms","remote":"127.0.0.1:46846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-09T05:53:30.745079Z","caller":"traceutil/trace.go:171","msg":"trace[218389040] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"216.009426ms","start":"2026-04-09T05:53:30.529014Z","end":"2026-04-09T05:53:30.745024Z","steps":["trace[218389040] 'process raft request' (duration: 175.275122ms)","trace[218389040] 'compare' (duration: 40.402853ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:53:30.745291Z","caller":"traceutil/trace.go:171","msg":"trace[1403947104] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"213.982276ms","start":"2026-04-09T05:53:30.531266Z","end":"2026-04-09T05:53:30.745248Z","steps":["trace[1403947104] 'process raft request' (duration: 213.640475ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:53:30.745295Z","caller":"traceutil/trace.go:171","msg":"trace[1023978546] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"207.884627ms","start":"2026-04-09T05:53:30.537381Z","end":"2026-04-09T05:53:30.745265Z","steps":["trace[1023978546] 'process raft request' (duration: 207.780647ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:53:30.910281Z","caller":"traceutil/trace.go:171","msg":"trace[1380907652] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"153.813791ms","start":"2026-04-09T05:53:30.756433Z","end":"2026-04-09T05:53:30.910247Z","steps":["trace[1380907652] 'process raft request' (duration: 94.142748ms)","trace[1380907652] 'compare' (duration: 59.472363ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:53:40.952037Z","caller":"traceutil/trace.go:171","msg":"trace[273464512] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"158.312848ms","start":"2026-04-09T05:53:40.793697Z","end":"2026-04-09T05:53:40.952009Z","steps":["trace[273464512] 'process raft request' (duration: 158.159536ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:53:42.148649Z","caller":"traceutil/trace.go:171","msg":"trace[445787288] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"106.402108ms","start":"2026-04-09T05:53:42.042223Z","end":"2026-04-09T05:53:42.148625Z","steps":["trace[445787288] 'process raft request' (duration: 106.183927ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:53:43.062003Z","caller":"traceutil/trace.go:171","msg":"trace[60977812] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:425; }","duration":"488.440465ms","start":"2026-04-09T05:53:42.573527Z","end":"2026-04-09T05:53:43.061968Z","steps":["trace[60977812] 'read index received' (duration: 473.92697ms)","trace[60977812] 'applied index is now lower than readState.Index' (duration: 14.511505ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:53:43.062303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.190243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-09T05:53:43.062368Z","caller":"traceutil/trace.go:171","msg":"trace[1683930642] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:414; }","duration":"468.310773ms","start":"2026-04-09T05:53:42.594038Z","end":"2026-04-09T05:53:43.062348Z","steps":["trace[1683930642] 'agreement among raft nodes before linearized reading' (duration: 468.142283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:53:43.062424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:53:42.594023Z","time spent":"468.388534ms","remote":"127.0.0.1:46846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-09T05:53:43.062387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.721256ms","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-04-09T05:53:43.062651Z","caller":"traceutil/trace.go:171","msg":"trace[1350306761] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:414; }","duration":"489.153968ms","start":"2026-04-09T05:53:42.573463Z","end":"2026-04-09T05:53:43.062617Z","steps":["trace[1350306761] 'agreement among raft nodes before linearized reading' (duration: 488.637225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:53:43.062722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.534334ms","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-09T05:53:43.062966Z","caller":"traceutil/trace.go:171","msg":"trace[1025742807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:414; }","duration":"105.820145ms","start":"2026-04-09T05:53:42.957122Z","end":"2026-04-09T05:53:43.062942Z","steps":["trace[1025742807] 'agreement among raft nodes before linearized reading' (duration: 105.522064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:53:43.062829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.133732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:53:43.063212Z","caller":"traceutil/trace.go:171","msg":"trace[1338313307] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:414; }","duration":"359.553305ms","start":"2026-04-09T05:53:42.703641Z","end":"2026-04-09T05:53:43.063194Z","steps":["trace[1338313307] 'agreement among raft nodes before linearized reading' (duration: 359.153863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:53:43.06327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:53:42.703619Z","time spent":"359.637505ms","remote":"127.0.0.1:46558","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-09T05:53:43.063448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:53:42.573437Z","time spent":"489.932471ms","remote":"127.0.0.1:46846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-09T05:54:02.730406Z","caller":"traceutil/trace.go:171","msg":"trace[923925879] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"535.203104ms","start":"2026-04-09T05:54:02.195169Z","end":"2026-04-09T05:54:02.730372Z","steps":["trace[923925879] 'process raft request' (duration: 535.048958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:02.730736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:02.195143Z","time spent":"535.477096ms","remote":"127.0.0.1:46636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T05:54:02.739259Z","caller":"traceutil/trace.go:171","msg":"trace[1354973986] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"541.312478ms","start":"2026-04-09T05:54:02.197921Z","end":"2026-04-09T05:54:02.739234Z","steps":["trace[1354973986] 'process raft request' (duration: 541.153761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:02.739408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:02.197899Z","time spent":"541.450914ms","remote":"127.0.0.1:46636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T05:54:02.739305Z","caller":"traceutil/trace.go:171","msg":"trace[1987838947] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"528.048875ms","start":"2026-04-09T05:54:02.211231Z","end":"2026-04-09T05:54:02.73928Z","steps":["trace[1987838947] 'process raft request' (duration: 527.93716ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:54:02.739616Z","caller":"traceutil/trace.go:171","msg":"trace[1139478957] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"524.335048ms","start":"2026-04-09T05:54:02.21527Z","end":"2026-04-09T05:54:02.739605Z","steps":["trace[1139478957] 'process raft request' (duration: 524.003884ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:54:02.739715Z","caller":"traceutil/trace.go:171","msg":"trace[1777293148] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"524.344558ms","start":"2026-04-09T05:54:02.215352Z","end":"2026-04-09T05:54:02.739696Z","steps":["trace[1777293148] 'process raft request' (duration: 523.954891ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:54:02.739765Z","caller":"traceutil/trace.go:171","msg":"trace[99480607] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"528.446633ms","start":"2026-04-09T05:54:02.21128Z","end":"2026-04-09T05:54:02.739727Z","steps":["trace[99480607] 'process raft request' (duration: 527.918309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:02.739806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:02.215348Z","time spent":"524.39204ms","remote":"127.0.0.1:46636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T05:54:02.739918Z","caller":"traceutil/trace.go:171","msg":"trace[426614576] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"520.767879ms","start":"2026-04-09T05:54:02.219137Z","end":"2026-04-09T05:54:02.739905Z","steps":["trace[426614576] 'process raft request' (duration: 520.223505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:02.740009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:02.219129Z","time spent":"520.854702ms","remote":"127.0.0.1:46636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T05:54:02.740063Z","caller":"traceutil/trace.go:171","msg":"trace[1163678410] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"520.672524ms","start":"2026-04-09T05:54:02.219362Z","end":"2026-04-09T05:54:02.740034Z","steps":["trace[1163678410] 'process raft request' (duration: 520.065147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:02.739927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:02.211277Z","time spent":"528.577008ms","remote":"127.0.0.1:46636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T05:54:02.740109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:02.219354Z","time spent":"520.734637ms","remote":"127.0.0.1:46636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T05:54:02.740325Z","caller":"traceutil/trace.go:171","msg":"trace[489214218] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"520.428054ms","start":"2026-04-09T05:54:02.219888Z","end":"2026-04-09T05:54:02.740316Z","steps":["trace[489214218] 'process raft request' (duration: 519.623208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:02.740324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:02.215252Z","time spent":"524.39839ms","remote":"127.0.0.1:46636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T05:54:02.740436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:02.219878Z","time spent":"520.511327ms","remote":"127.0.0.1:46636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T05:54:02.7405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:02.211216Z","time spent":"528.352829ms","remote":"127.0.0.1:46636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T05:54:02.740721Z","caller":"traceutil/trace.go:171","msg":"trace[1039150416] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"499.394042ms","start":"2026-04-09T05:54:02.241308Z","end":"2026-04-09T05:54:02.740702Z","steps":["trace[1039150416] 'process raft request' (duration: 499.221084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:02.740809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:02.241281Z","time spent":"499.493286ms","remote":"127.0.0.1:46636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T05:54:02.740851Z","caller":"traceutil/trace.go:171","msg":"trace[736453454] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"484.441453ms","start":"2026-04-09T05:54:02.256338Z","end":"2026-04-09T05:54:02.740779Z","steps":["trace[736453454] 'process raft request' (duration: 484.287427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:02.740957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:02.256318Z","time spent":"484.612511ms","remote":"127.0.0.1:46636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T05:54:02.741061Z","caller":"traceutil/trace.go:171","msg":"trace[1443017599] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"366.809881ms","start":"2026-04-09T05:54:02.374242Z","end":"2026-04-09T05:54:02.741052Z","steps":["trace[1443017599] 'process raft request' (duration: 366.435644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:02.741097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:02.374211Z","time spent":"366.870964ms","remote":"127.0.0.1:46636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T05:54:05.462949Z","caller":"traceutil/trace.go:171","msg":"trace[1115631312] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"243.219487ms","start":"2026-04-09T05:54:05.219699Z","end":"2026-04-09T05:54:05.462919Z","steps":["trace[1115631312] 'process raft request' (duration: 243.021658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:05.463262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.126179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-09T05:54:05.4633Z","caller":"traceutil/trace.go:171","msg":"trace[899059402] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"148.768155ms","start":"2026-04-09T05:54:05.314119Z","end":"2026-04-09T05:54:05.462887Z","steps":["trace[899059402] 'read index received' (duration: 148.560516ms)","trace[899059402] 'applied index is now lower than readState.Index' (duration: 206.589µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:54:05.46332Z","caller":"traceutil/trace.go:171","msg":"trace[159481623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:605; }","duration":"149.241795ms","start":"2026-04-09T05:54:05.314065Z","end":"2026-04-09T05:54:05.463307Z","steps":["trace[159481623] 'agreement among raft nodes before linearized reading' (duration: 148.934741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:05.463448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.06835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-09T05:54:05.463483Z","caller":"traceutil/trace.go:171","msg":"trace[749127397] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:605; }","duration":"147.159774ms","start":"2026-04-09T05:54:05.316309Z","end":"2026-04-09T05:54:05.463469Z","steps":["trace[749127397] 'agreement among raft nodes before linearized reading' (duration: 147.066ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:54:05.595158Z","caller":"traceutil/trace.go:171","msg":"trace[1341224673] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:627; }","duration":"125.336663ms","start":"2026-04-09T05:54:05.469743Z","end":"2026-04-09T05:54:05.595079Z","steps":["trace[1341224673] 'read index received' (duration: 14.414212ms)","trace[1341224673] 'applied index is now lower than readState.Index' (duration: 110.921161ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:54:05.595205Z","caller":"traceutil/trace.go:171","msg":"trace[285388671] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"126.818456ms","start":"2026-04-09T05:54:05.468317Z","end":"2026-04-09T05:54:05.595135Z","steps":["trace[285388671] 'process raft request' (duration: 50.09656ms)","trace[285388671] 'compare' (duration: 76.538918ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:54:05.595319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.545611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-envoy-gateway-role\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:54:05.59536Z","caller":"traceutil/trace.go:171","msg":"trace[1940529322] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-envoy-gateway-role; range_end:; response_count:0; response_revision:607; }","duration":"125.656716ms","start":"2026-04-09T05:54:05.469688Z","end":"2026-04-09T05:54:05.595345Z","steps":["trace[1940529322] 'agreement among raft nodes before linearized reading' (duration: 125.562342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:09.919377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.955949ms","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-04-09T05:54:09.919551Z","caller":"traceutil/trace.go:171","msg":"trace[1309150440] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"430.166828ms","start":"2026-04-09T05:54:09.489361Z","end":"2026-04-09T05:54:09.919528Z","steps":["trace[1309150440] 'range keys from in-memory index tree' (duration: 429.827704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:09.919377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.740966ms","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-09T05:54:09.919666Z","caller":"traceutil/trace.go:171","msg":"trace[1325347852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:634; }","duration":"417.07135ms","start":"2026-04-09T05:54:09.502581Z","end":"2026-04-09T05:54:09.919652Z","steps":["trace[1325347852] 'range keys from in-memory index tree' (duration: 416.60294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:09.919708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:09.50257Z","time spent":"417.125361ms","remote":"127.0.0.1:46846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T05:54:09.919644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:09.489349Z","time spent":"430.262211ms","remote":"127.0.0.1:46846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-09T05:54:10.110816Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346108245820165702,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-09T05:54:10.281716Z","caller":"traceutil/trace.go:171","msg":"trace[734149427] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"739.815359ms","start":"2026-04-09T05:54:09.541869Z","end":"2026-04-09T05:54:10.281684Z","steps":["trace[734149427] 'process raft request' (duration: 739.183143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:10.281984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:09.541835Z","time spent":"739.973105ms","remote":"127.0.0.1:46676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T05:54:10.411524Z","caller":"traceutil/trace.go:171","msg":"trace[598516172] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:657; }","duration":"801.748298ms","start":"2026-04-09T05:54:09.609723Z","end":"2026-04-09T05:54:10.411472Z","steps":["trace[598516172] 'read index received' (duration: 671.069236ms)","trace[598516172] 'applied index is now lower than readState.Index' (duration: 130.677562ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:54:10.411613Z","caller":"traceutil/trace.go:171","msg":"trace[1671380793] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"487.858047ms","start":"2026-04-09T05:54:09.923673Z","end":"2026-04-09T05:54:10.411531Z","steps":["trace[1671380793] 'process raft request' (duration: 401.861119ms)","trace[1671380793] 'compare' (duration: 85.666545ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:54:10.41172Z","caller":"traceutil/trace.go:171","msg":"trace[842997248] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"487.960943ms","start":"2026-04-09T05:54:09.923746Z","end":"2026-04-09T05:54:10.411707Z","steps":["trace[842997248] 'process raft request' (duration: 487.66197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:10.411885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:09.923728Z","time spent":"488.033436ms","remote":"127.0.0.1:46846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T05:54:10.412088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:09.923641Z","time spent":"488.084967ms","remote":"127.0.0.1:46846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T05:54:10.412134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.383505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-09T05:54:10.412269Z","caller":"traceutil/trace.go:171","msg":"trace[1053543356] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:637; }","duration":"802.532691ms","start":"2026-04-09T05:54:09.609689Z","end":"2026-04-09T05:54:10.412222Z","steps":["trace[1053543356] 'agreement among raft nodes before linearized reading' (duration: 802.090073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:10.41235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:09.609672Z","time spent":"802.640995ms","remote":"127.0.0.1:46846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-09T05:54:10.49944Z","caller":"traceutil/trace.go:171","msg":"trace[797619191] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"154.845933ms","start":"2026-04-09T05:54:10.344549Z","end":"2026-04-09T05:54:10.499395Z","steps":["trace[797619191] 'process raft request' (duration: 154.616484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:10.499632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.309039ms","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-09T05:54:10.49969Z","caller":"traceutil/trace.go:171","msg":"trace[1505530160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:638; }","duration":"339.395691ms","start":"2026-04-09T05:54:10.160272Z","end":"2026-04-09T05:54:10.499668Z","steps":["trace[1505530160] 'agreement among raft nodes before linearized reading' (duration: 339.303888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:10.499769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:10.160248Z","time spent":"339.504256ms","remote":"127.0.0.1:46846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T05:54:10.499513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.061291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rh88c\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-09T05:54:10.500154Z","caller":"traceutil/trace.go:171","msg":"trace[1586001118] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rh88c; range_end:; response_count:1; response_revision:638; }","duration":"795.741648ms","start":"2026-04-09T05:54:09.704394Z","end":"2026-04-09T05:54:10.500135Z","steps":["trace[1586001118] 'agreement among raft nodes before linearized reading' (duration: 794.965327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:10.500276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:09.704373Z","time spent":"795.872434ms","remote":"127.0.0.1:46756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-rh88c\" "} {"level":"warn","ts":"2026-04-09T05:54:11.003928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.134515ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:54:11.004039Z","caller":"traceutil/trace.go:171","msg":"trace[850614270] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"497.82018ms","start":"2026-04-09T05:54:10.50611Z","end":"2026-04-09T05:54:11.00393Z","steps":["trace[850614270] 'read index received' (duration: 488.088234ms)","trace[850614270] 'applied index is now lower than readState.Index' (duration: 9.730496ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:54:11.004071Z","caller":"traceutil/trace.go:171","msg":"trace[103446151] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:638; }","duration":"413.287291ms","start":"2026-04-09T05:54:10.590761Z","end":"2026-04-09T05:54:11.004048Z","steps":["trace[103446151] 'range keys from in-memory index tree' (duration: 413.107934ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:54:11.00416Z","caller":"traceutil/trace.go:171","msg":"trace[184627784] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"586.929745ms","start":"2026-04-09T05:54:10.417213Z","end":"2026-04-09T05:54:11.004143Z","steps":["trace[184627784] 'process raft request' (duration: 577.021543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:11.004282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:10.417193Z","time spent":"586.993909ms","remote":"127.0.0.1:46846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T05:54:11.00436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.244257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-09T05:54:11.004437Z","caller":"traceutil/trace.go:171","msg":"trace[701339440] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:639; }","duration":"498.355332ms","start":"2026-04-09T05:54:10.506056Z","end":"2026-04-09T05:54:11.004411Z","steps":["trace[701339440] 'agreement among raft nodes before linearized reading' (duration: 498.222747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:11.004478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:10.506032Z","time spent":"498.437506ms","remote":"127.0.0.1:46782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"info","ts":"2026-04-09T05:54:11.035612Z","caller":"traceutil/trace.go:171","msg":"trace[683612214] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"528.49359ms","start":"2026-04-09T05:54:10.507087Z","end":"2026-04-09T05:54:11.03558Z","steps":["trace[683612214] 'process raft request' (duration: 528.318403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:11.03577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:10.507064Z","time spent":"528.625675ms","remote":"127.0.0.1:46846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T05:54:11.035927Z","caller":"traceutil/trace.go:171","msg":"trace[1804154541] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"522.57928ms","start":"2026-04-09T05:54:10.513285Z","end":"2026-04-09T05:54:11.035864Z","steps":["trace[1804154541] 'process raft request' (duration: 522.231547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:11.036131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:10.513252Z","time spent":"522.778948ms","remote":"127.0.0.1:46756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T05:54:11.036221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.910303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:54:11.036273Z","caller":"traceutil/trace.go:171","msg":"trace[1150138920] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"330.998907ms","start":"2026-04-09T05:54:10.70526Z","end":"2026-04-09T05:54:11.036259Z","steps":["trace[1150138920] 'agreement among raft nodes before linearized reading' (duration: 330.918514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:11.03631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:10.705239Z","time spent":"331.06418ms","remote":"127.0.0.1:46542","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-09T05:54:12.141861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.871476ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-09T05:54:12.142001Z","caller":"traceutil/trace.go:171","msg":"trace[1116267130] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"1.074663971s","start":"2026-04-09T05:54:11.067312Z","end":"2026-04-09T05:54:12.141976Z","steps":["trace[1116267130] 'process raft request' (duration: 363.608177ms)","trace[1116267130] 'compare' (duration: 710.624927ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:54:12.142076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:11.067286Z","time spent":"1.074756254s","remote":"127.0.0.1:46652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T05:54:12.809891Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.378891942s","expected-duration":"1s"} {"level":"info","ts":"2026-04-09T05:54:12.825543Z","caller":"traceutil/trace.go:171","msg":"trace[219707051] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:666; }","duration":"408.365258ms","start":"2026-04-09T05:54:12.417157Z","end":"2026-04-09T05:54:12.825522Z","steps":["trace[219707051] 'read index received' (duration: 393.083833ms)","trace[219707051] 'applied index is now lower than readState.Index' (duration: 15.280415ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:54:12.825682Z","caller":"traceutil/trace.go:171","msg":"trace[1664195776] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"1.758215372s","start":"2026-04-09T05:54:11.067418Z","end":"2026-04-09T05:54:12.825634Z","steps":["trace[1664195776] 'process raft request' (duration: 1.742911986s)","trace[1664195776] 'compare' (duration: 14.582317ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:54:12.825805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:11.067384Z","time spent":"1.758335076s","remote":"127.0.0.1:46652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T05:54:12.826138Z","caller":"traceutil/trace.go:171","msg":"trace[1135341324] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"1.758158318s","start":"2026-04-09T05:54:11.067953Z","end":"2026-04-09T05:54:12.826111Z","steps":["trace[1135341324] 'process raft request' (duration: 1.757453741s)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:54:12.82614Z","caller":"traceutil/trace.go:171","msg":"trace[1526505305] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"1.757903199s","start":"2026-04-09T05:54:11.068218Z","end":"2026-04-09T05:54:12.826121Z","steps":["trace[1526505305] 'process raft request' (duration: 1.757261704s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:12.826344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:11.067932Z","time spent":"1.758321905s","remote":"127.0.0.1:46756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T05:54:12.826443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:11.068208Z","time spent":"1.758158038s","remote":"127.0.0.1:46824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T05:54:12.826303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.121508ms","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-04-09T05:54:12.826654Z","caller":"traceutil/trace.go:171","msg":"trace[838152626] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:646; }","duration":"409.513595ms","start":"2026-04-09T05:54:12.41711Z","end":"2026-04-09T05:54:12.826623Z","steps":["trace[838152626] 'agreement among raft nodes before linearized reading' (duration: 409.016474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:12.826751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:12.417032Z","time spent":"409.705342ms","remote":"127.0.0.1:46846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-09T05:54:12.837804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.543632ms","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-09T05:54:12.837855Z","caller":"traceutil/trace.go:171","msg":"trace[81712898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:647; }","duration":"420.605254ms","start":"2026-04-09T05:54:12.417237Z","end":"2026-04-09T05:54:12.837842Z","steps":["trace[81712898] 'agreement among raft nodes before linearized reading' (duration: 420.28384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:12.837887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:12.41723Z","time spent":"420.648255ms","remote":"127.0.0.1:46846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T05:54:12.838154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.978588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:54:12.838243Z","caller":"traceutil/trace.go:171","msg":"trace[12203339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"131.591203ms","start":"2026-04-09T05:54:12.706606Z","end":"2026-04-09T05:54:12.838197Z","steps":["trace[12203339] 'agreement among raft nodes before linearized reading' (duration: 130.926966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:28.912864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.207636086s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:54:28.912954Z","caller":"traceutil/trace.go:171","msg":"trace[775388376] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"1.20778775s","start":"2026-04-09T05:54:27.705146Z","end":"2026-04-09T05:54:28.912934Z","steps":["trace[775388376] 'range keys from in-memory index tree' (duration: 1.207528062s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:28.912995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:27.705117Z","time spent":"1.207869173s","remote":"127.0.0.1:46542","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-09T05:54:28.912989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.124266ms","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-09T05:54:28.913116Z","caller":"traceutil/trace.go:171","msg":"trace[2137579074] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:802; }","duration":"448.369783ms","start":"2026-04-09T05:54:28.464718Z","end":"2026-04-09T05:54:28.913088Z","steps":["trace[2137579074] 'range keys from in-memory index tree' (duration: 447.770304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:28.913177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:28.46469Z","time spent":"448.475987ms","remote":"127.0.0.1:46846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-09T05:54:28.913158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.072308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:54:28.913281Z","caller":"traceutil/trace.go:171","msg":"trace[738806552] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:802; }","duration":"285.275344ms","start":"2026-04-09T05:54:28.627977Z","end":"2026-04-09T05:54:28.913252Z","steps":["trace[738806552] 'count revisions from in-memory index tree' (duration: 284.953264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:28.913482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.103645643s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-z8zm6\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-04-09T05:54:28.913596Z","caller":"traceutil/trace.go:171","msg":"trace[758925130] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-z8zm6; range_end:; response_count:1; response_revision:802; }","duration":"1.103826648s","start":"2026-04-09T05:54:27.80974Z","end":"2026-04-09T05:54:28.913566Z","steps":["trace[758925130] 'range keys from in-memory index tree' (duration: 1.103356753s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:28.913648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:54:27.809706Z","time spent":"1.103929042s","remote":"127.0.0.1:46756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-z8zm6\" "} {"level":"info","ts":"2026-04-09T05:54:34.200712Z","caller":"traceutil/trace.go:171","msg":"trace[328231987] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"128.260403ms","start":"2026-04-09T05:54:34.072433Z","end":"2026-04-09T05:54:34.200694Z","steps":["trace[328231987] 'process raft request' (duration: 128.16568ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:54:43.161772Z","caller":"traceutil/trace.go:171","msg":"trace[1614295645] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"144.560659ms","start":"2026-04-09T05:54:43.017192Z","end":"2026-04-09T05:54:43.161753Z","steps":["trace[1614295645] 'process raft request' (duration: 144.445196ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:54:43.163566Z","caller":"traceutil/trace.go:171","msg":"trace[1172610305] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:997; }","duration":"132.136142ms","start":"2026-04-09T05:54:43.03141Z","end":"2026-04-09T05:54:43.163546Z","steps":["trace[1172610305] 'read index received' (duration: 130.269699ms)","trace[1172610305] 'applied index is now lower than readState.Index' (duration: 1.865473ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:54:43.163609Z","caller":"traceutil/trace.go:171","msg":"trace[352516352] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"146.34383ms","start":"2026-04-09T05:54:43.017246Z","end":"2026-04-09T05:54:43.16359Z","steps":["trace[352516352] 'process raft request' (duration: 146.176675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:43.163856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.43392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-09T05:54:43.163899Z","caller":"traceutil/trace.go:171","msg":"trace[1606943882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:967; }","duration":"132.510882ms","start":"2026-04-09T05:54:43.031378Z","end":"2026-04-09T05:54:43.163889Z","steps":["trace[1606943882] 'agreement among raft nodes before linearized reading' (duration: 132.277736ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:56:19.081362Z","caller":"traceutil/trace.go:171","msg":"trace[1730313676] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"108.675044ms","start":"2026-04-09T05:56:18.97266Z","end":"2026-04-09T05:56:19.081335Z","steps":["trace[1730313676] 'process raft request' (duration: 108.460571ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:56:20.076505Z","caller":"traceutil/trace.go:171","msg":"trace[1889626675] linearizableReadLoop","detail":"{readStateIndex:1389; appliedIndex:1388; }","duration":"384.283561ms","start":"2026-04-09T05:56:19.692202Z","end":"2026-04-09T05:56:20.076485Z","steps":["trace[1889626675] 'read index received' (duration: 384.148669ms)","trace[1889626675] 'applied index is now lower than readState.Index' (duration: 134.132µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:56:20.076534Z","caller":"traceutil/trace.go:171","msg":"trace[1827562693] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"449.587713ms","start":"2026-04-09T05:56:19.626923Z","end":"2026-04-09T05:56:20.07651Z","steps":["trace[1827562693] 'process raft request' (duration: 449.445751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:56:20.076682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:56:19.626889Z","time spent":"449.718925ms","remote":"127.0.0.1:46846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T05:56:20.07672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.501173ms","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-09T05:56:20.076755Z","caller":"traceutil/trace.go:171","msg":"trace[861287764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1335; }","duration":"384.575036ms","start":"2026-04-09T05:56:19.69217Z","end":"2026-04-09T05:56:20.076745Z","steps":["trace[861287764] 'agreement among raft nodes before linearized reading' (duration: 384.466805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:56:20.076778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:56:19.69215Z","time spent":"384.622826ms","remote":"127.0.0.1:46846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T05:56:20.076823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.157022ms","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-09T05:56:20.076869Z","caller":"traceutil/trace.go:171","msg":"trace[1437631287] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1335; }","duration":"371.204893ms","start":"2026-04-09T05:56:19.705651Z","end":"2026-04-09T05:56:20.076856Z","steps":["trace[1437631287] 'agreement among raft nodes before linearized reading' (duration: 371.062531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:56:20.076849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.233536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-09T05:56:20.076904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:56:19.705624Z","time spent":"371.272704ms","remote":"127.0.0.1:46846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-09T05:56:20.076928Z","caller":"traceutil/trace.go:171","msg":"trace[448930422] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1335; }","duration":"380.327727ms","start":"2026-04-09T05:56:19.696583Z","end":"2026-04-09T05:56:20.07691Z","steps":["trace[448930422] 'agreement among raft nodes before linearized reading' (duration: 380.094434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:56:20.076973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:56:19.69656Z","time spent":"380.405038ms","remote":"127.0.0.1:46846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-09T05:56:20.077023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.519821ms","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-09T05:56:20.077044Z","caller":"traceutil/trace.go:171","msg":"trace[131149459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1335; }","duration":"372.581671ms","start":"2026-04-09T05:56:19.704457Z","end":"2026-04-09T05:56:20.077039Z","steps":["trace[131149459] 'agreement among raft nodes before linearized reading' (duration: 372.52506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:56:20.077064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:56:19.704438Z","time spent":"372.619722ms","remote":"127.0.0.1:46846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}