{"level":"warn","ts":"2026-04-07T05:50:52.267987Z","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-07T05:50:52.268065Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.205: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.205:2380","--initial-cluster=instance=https://199.204.45.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.205: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-07T05:50:52.268127Z","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-07T05:50:52.268135Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-04-07T05:50:52.268153Z","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-07T05:50:52.268851Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"]} {"level":"info","ts":"2026-04-07T05:50:52.269081Z","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.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.205: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-07T05:50:52.275834Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.312151ms"} {"level":"info","ts":"2026-04-07T05:50:52.284471Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"71d28e9ac1bc4e5d","cluster-id":"4a4ae660c7a9a6a7"} {"level":"info","ts":"2026-04-07T05:50:52.28455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T05:50:52.284569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 0"} {"level":"info","ts":"2026-04-07T05:50:52.284577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 71d28e9ac1bc4e5d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T05:50:52.284583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 1"} {"level":"info","ts":"2026-04-07T05:50:52.284614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"warn","ts":"2026-04-07T05:50:52.291889Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T05:50:52.294041Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T05:50:52.295052Z","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-07T05:50:52.297237Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"71d28e9ac1bc4e5d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T05:50:52.297412Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"71d28e9ac1bc4e5d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T05:50:52.2978Z","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-07T05:50:52.297877Z","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-07T05:50:52.299664Z","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-07T05:50:52.300596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"info","ts":"2026-04-07T05:50:52.300791Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","added-peer-id":"71d28e9ac1bc4e5d","added-peer-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-04-07T05:50:52.30336Z","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-07T05:50:52.303446Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-04-07T05:50:52.303497Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-04-07T05:50:52.303638Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"71d28e9ac1bc4e5d","initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T05:50:52.303698Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T05:50:53.085376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T05:50:53.08544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T05:50:53.085496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgPreVoteResp from 71d28e9ac1bc4e5d at term 1"} {"level":"info","ts":"2026-04-07T05:50:53.085526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became candidate at term 2"} {"level":"info","ts":"2026-04-07T05:50:53.085538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgVoteResp from 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-04-07T05:50:53.085559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became leader at term 2"} {"level":"info","ts":"2026-04-07T05:50:53.085577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 71d28e9ac1bc4e5d elected leader 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-04-07T05:50:53.088696Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T05:50:53.088942Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T05:50:53.088965Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T05:50:53.08941Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T05:50:53.089445Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T05:50:53.089475Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"71d28e9ac1bc4e5d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.205:2379]}","request-path":"/0/members/71d28e9ac1bc4e5d/attributes","cluster-id":"4a4ae660c7a9a6a7","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T05:50:53.09087Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T05:50:53.091165Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T05:50:53.091368Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T05:50:53.092747Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T05:50:53.099906Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.205:2379"} {"level":"info","ts":"2026-04-07T05:50:56.458719Z","caller":"traceutil/trace.go:171","msg":"trace[287869748] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"482.0248ms","start":"2026-04-07T05:50:55.97667Z","end":"2026-04-07T05:50:56.458694Z","steps":["trace[287869748] 'process raft request' (duration: 481.981689ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:50:56.459128Z","caller":"traceutil/trace.go:171","msg":"trace[1924078998] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"527.043134ms","start":"2026-04-07T05:50:55.932033Z","end":"2026-04-07T05:50:56.459076Z","steps":["trace[1924078998] 'process raft request' (duration: 508.058183ms)","trace[1924078998] 'compare' (duration: 18.424366ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:50:56.459623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:50:55.976646Z","time spent":"482.178425ms","remote":"127.0.0.1:35292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1069,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T05:50:56.459813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:50:55.932017Z","time spent":"527.196647ms","remote":"127.0.0.1:35400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T05:50:59.881498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.519663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"warn","ts":"2026-04-07T05:50:59.881554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.9046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:50:59.881616Z","caller":"traceutil/trace.go:171","msg":"trace[1156693603] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:322; }","duration":"371.703786ms","start":"2026-04-07T05:50:59.509894Z","end":"2026-04-07T05:50:59.881598Z","steps":["trace[1156693603] 'range keys from in-memory index tree' (duration: 371.417238ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:50:59.881654Z","caller":"traceutil/trace.go:171","msg":"trace[1473836754] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:0; response_revision:322; }","duration":"425.075215ms","start":"2026-04-07T05:50:59.45656Z","end":"2026-04-07T05:50:59.881635Z","steps":["trace[1473836754] 'range keys from in-memory index tree' (duration: 424.813279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:50:59.881669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:50:59.509867Z","time spent":"371.79094ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"warn","ts":"2026-04-07T05:50:59.881703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:50:59.456541Z","time spent":"425.151428ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" "} {"level":"info","ts":"2026-04-07T05:50:59.996311Z","caller":"traceutil/trace.go:171","msg":"trace[774790944] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"108.607887ms","start":"2026-04-07T05:50:59.887677Z","end":"2026-04-07T05:50:59.996285Z","steps":["trace[774790944] 'process raft request' (duration: 108.461203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:51:00.569485Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646842571325144808,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T05:51:00.918435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.533135ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:51:00.918562Z","caller":"traceutil/trace.go:171","msg":"trace[277154350] linearizableReadLoop","detail":"{readStateIndex:335; appliedIndex:334; }","duration":"849.3527ms","start":"2026-04-07T05:51:00.069187Z","end":"2026-04-07T05:51:00.91854Z","steps":["trace[277154350] 'read index received' (duration: 625.336701ms)","trace[277154350] 'applied index is now lower than readState.Index' (duration: 224.014078ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:51:00.918617Z","caller":"traceutil/trace.go:171","msg":"trace[480845863] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"903.843939ms","start":"2026-04-07T05:51:00.01473Z","end":"2026-04-07T05:51:00.918574Z","steps":["trace[480845863] 'process raft request' (duration: 679.866012ms)","trace[480845863] 'compare' (duration: 223.36592ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:51:00.918751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.550735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-07T05:51:00.918782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:51:00.014716Z","time spent":"903.994052ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T05:51:00.918826Z","caller":"traceutil/trace.go:171","msg":"trace[1911720410] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:324; }","duration":"849.658778ms","start":"2026-04-07T05:51:00.069153Z","end":"2026-04-07T05:51:00.918812Z","steps":["trace[1911720410] 'agreement among raft nodes before linearized reading' (duration: 849.489213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:51:00.918885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:51:00.069139Z","time spent":"849.733721ms","remote":"127.0.0.1:35476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T05:51:00.918843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.761036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T05:51:00.918946Z","caller":"traceutil/trace.go:171","msg":"trace[340618012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:324; }","duration":"418.90755ms","start":"2026-04-07T05:51:00.500024Z","end":"2026-04-07T05:51:00.918931Z","steps":["trace[340618012] 'agreement among raft nodes before linearized reading' (duration: 418.671664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:51:00.919201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:51:00.500001Z","time spent":"419.051704ms","remote":"127.0.0.1:35476","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-07T05:51:01.270474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.352593ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:51:01.270596Z","caller":"traceutil/trace.go:171","msg":"trace[524532202] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"346.537449ms","start":"2026-04-07T05:51:00.924035Z","end":"2026-04-07T05:51:01.270573Z","steps":["trace[524532202] 'process raft request' (duration: 202.832936ms)","trace[524532202] 'compare' (duration: 143.2321ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:51:01.270664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:51:00.923921Z","time spent":"346.714975ms","remote":"127.0.0.1:35476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:51:01.271143Z","caller":"traceutil/trace.go:171","msg":"trace[11694457] linearizableReadLoop","detail":"{readStateIndex:337; appliedIndex:335; }","duration":"345.610794ms","start":"2026-04-07T05:51:00.925498Z","end":"2026-04-07T05:51:01.271109Z","steps":["trace[11694457] 'read index received' (duration: 201.435669ms)","trace[11694457] 'applied index is now lower than readState.Index' (duration: 144.174185ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:51:01.271259Z","caller":"traceutil/trace.go:171","msg":"trace[1488191462] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"347.185606ms","start":"2026-04-07T05:51:00.924062Z","end":"2026-04-07T05:51:01.271248Z","steps":["trace[1488191462] 'process raft request' (duration: 346.947689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:51:01.27155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.843854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-07T05:51:01.271616Z","caller":"traceutil/trace.go:171","msg":"trace[1255975533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:326; }","duration":"170.945017ms","start":"2026-04-07T05:51:01.100659Z","end":"2026-04-07T05:51:01.271604Z","steps":["trace[1255975533] 'agreement among raft nodes before linearized reading' (duration: 170.854094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:51:01.271627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.126228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-07T05:51:01.271679Z","caller":"traceutil/trace.go:171","msg":"trace[1104773752] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:326; }","duration":"346.19869ms","start":"2026-04-07T05:51:00.925468Z","end":"2026-04-07T05:51:01.271666Z","steps":["trace[1104773752] 'agreement among raft nodes before linearized reading' (duration: 346.108458ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:51:01.271719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:51:00.925459Z","time spent":"346.253162ms","remote":"127.0.0.1:35302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-07T05:51:01.271359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:51:00.924049Z","time spent":"347.239508ms","remote":"127.0.0.1:35476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:51:22.883349Z","caller":"traceutil/trace.go:171","msg":"trace[1488817353] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"231.192934ms","start":"2026-04-07T05:51:22.652125Z","end":"2026-04-07T05:51:22.883318Z","steps":["trace[1488817353] 'process raft request' (duration: 154.643844ms)","trace[1488817353] 'compare' (duration: 76.378156ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:51:22.997648Z","caller":"traceutil/trace.go:171","msg":"trace[1828242653] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"338.268778ms","start":"2026-04-07T05:51:22.659341Z","end":"2026-04-07T05:51:22.99761Z","steps":["trace[1828242653] 'process raft request' (duration: 338.00349ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:51:22.997946Z","caller":"traceutil/trace.go:171","msg":"trace[24985432] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"338.049132ms","start":"2026-04-07T05:51:22.659657Z","end":"2026-04-07T05:51:22.997706Z","steps":["trace[24985432] 'process raft request' (duration: 337.839196ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:51:22.998012Z","caller":"traceutil/trace.go:171","msg":"trace[1845140100] transaction","detail":"{read_only:false; number_of_response:1; response_revision:517; }","duration":"336.322195ms","start":"2026-04-07T05:51:22.66165Z","end":"2026-04-07T05:51:22.997972Z","steps":["trace[1845140100] 'process raft request' (duration: 335.952726ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:51:22.998008Z","caller":"traceutil/trace.go:171","msg":"trace[1176617040] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"338.053832ms","start":"2026-04-07T05:51:22.659719Z","end":"2026-04-07T05:51:22.997773Z","steps":["trace[1176617040] 'process raft request' (duration: 337.845516ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:51:22.998155Z","caller":"traceutil/trace.go:171","msg":"trace[1236850882] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"327.820286ms","start":"2026-04-07T05:51:22.67032Z","end":"2026-04-07T05:51:22.99814Z","steps":["trace[1236850882] 'process raft request' (duration: 327.452126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:51:22.998195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:51:22.661644Z","time spent":"336.454609ms","remote":"127.0.0.1:35242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10111,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:51:22.998303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:51:22.670316Z","time spent":"327.910268ms","remote":"127.0.0.1:35242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T05:51:22.997997Z","caller":"traceutil/trace.go:171","msg":"trace[1928367784] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"327.644473ms","start":"2026-04-07T05:51:22.670283Z","end":"2026-04-07T05:51:22.997927Z","steps":["trace[1928367784] 'process raft request' (duration: 327.437897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:51:22.99852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:51:22.670277Z","time spent":"328.143895ms","remote":"127.0.0.1:35242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T05:51:22.998508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:51:22.659648Z","time spent":"338.501273ms","remote":"127.0.0.1:35242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:51:22.998467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:51:22.659714Z","time spent":"338.516545ms","remote":"127.0.0.1:35242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T05:51:22.998774Z","caller":"traceutil/trace.go:171","msg":"trace[1730313898] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"328.69283ms","start":"2026-04-07T05:51:22.669181Z","end":"2026-04-07T05:51:22.997874Z","steps":["trace[1730313898] 'process raft request' (duration: 328.473004ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:51:22.998821Z","caller":"traceutil/trace.go:171","msg":"trace[1483019359] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"327.708723ms","start":"2026-04-07T05:51:22.671034Z","end":"2026-04-07T05:51:22.998742Z","steps":["trace[1483019359] 'process raft request' (duration: 326.805469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:51:22.999097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:51:22.669172Z","time spent":"329.756298ms","remote":"127.0.0.1:35242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T05:51:22.999144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:51:22.671028Z","time spent":"327.897638ms","remote":"127.0.0.1:35242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T05:51:22.999426Z","caller":"traceutil/trace.go:171","msg":"trace[1225148686] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:530; }","duration":"276.274369ms","start":"2026-04-07T05:51:22.723129Z","end":"2026-04-07T05:51:22.999403Z","steps":["trace[1225148686] 'read index received' (duration: 83.635013ms)","trace[1225148686] 'applied index is now lower than readState.Index' (duration: 192.638276ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:51:22.999435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:51:22.659319Z","time spent":"338.441062ms","remote":"127.0.0.1:35732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T05:51:22.999566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.434793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:51:22.999619Z","caller":"traceutil/trace.go:171","msg":"trace[462597690] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"276.519574ms","start":"2026-04-07T05:51:22.723085Z","end":"2026-04-07T05:51:22.999605Z","steps":["trace[462597690] 'agreement among raft nodes before linearized reading' (duration: 276.406411ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:51:22.999809Z","caller":"traceutil/trace.go:171","msg":"trace[611203019] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"300.598503ms","start":"2026-04-07T05:51:22.699192Z","end":"2026-04-07T05:51:22.999791Z","steps":["trace[611203019] 'process raft request' (duration: 300.029808ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:51:22.99986Z","caller":"traceutil/trace.go:171","msg":"trace[1609045985] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"199.44989ms","start":"2026-04-07T05:51:22.800392Z","end":"2026-04-07T05:51:22.999842Z","steps":["trace[1609045985] 'process raft request' (duration: 198.920977ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:51:22.999823Z","caller":"traceutil/trace.go:171","msg":"trace[102847767] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"312.37117ms","start":"2026-04-07T05:51:22.687336Z","end":"2026-04-07T05:51:22.999707Z","steps":["trace[102847767] 'process raft request' (duration: 311.786345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:51:23.001012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:51:22.687301Z","time spent":"313.655395ms","remote":"127.0.0.1:35242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T05:51:22.999993Z","caller":"traceutil/trace.go:171","msg":"trace[1609641280] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"325.34267ms","start":"2026-04-07T05:51:22.674638Z","end":"2026-04-07T05:51:22.99998Z","steps":["trace[1609641280] 'process raft request' (duration: 324.079755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:51:22.999898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:51:22.699175Z","time spent":"300.687646ms","remote":"127.0.0.1:35242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T05:51:23.001507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:51:22.674619Z","time spent":"326.85417ms","remote":"127.0.0.1:35242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T05:51:29.414336Z","caller":"traceutil/trace.go:171","msg":"trace[1777725977] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"307.193831ms","start":"2026-04-07T05:51:29.107102Z","end":"2026-04-07T05:51:29.414296Z","steps":["trace[1777725977] 'process raft request' (duration: 306.835962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:51:29.414562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:51:29.107086Z","time spent":"307.365276ms","remote":"127.0.0.1:35396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:51:29.418462Z","caller":"traceutil/trace.go:171","msg":"trace[2036031306] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"294.762056ms","start":"2026-04-07T05:51:29.123661Z","end":"2026-04-07T05:51:29.418423Z","steps":["trace[2036031306] 'process raft request' (duration: 294.589602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:51:29.937482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.233643ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:51:29.937664Z","caller":"traceutil/trace.go:171","msg":"trace[242844240] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"510.205436ms","start":"2026-04-07T05:51:29.427418Z","end":"2026-04-07T05:51:29.937624Z","steps":["trace[242844240] 'process raft request' (duration: 147.737858ms)","trace[242844240] 'compare' (duration: 361.932934ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:51:29.937804Z","caller":"traceutil/trace.go:171","msg":"trace[1798424120] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"506.97476ms","start":"2026-04-07T05:51:29.43081Z","end":"2026-04-07T05:51:29.937785Z","steps":["trace[1798424120] 'read index received' (duration: 249.807µs)","trace[1798424120] 'applied index is now lower than readState.Index' (duration: 506.723533ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:51:29.937857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:51:29.427411Z","time spent":"510.3361ms","remote":"127.0.0.1:35476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:51:29.937905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.089383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:51:29.93794Z","caller":"traceutil/trace.go:171","msg":"trace[2082933616] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:620; }","duration":"507.164215ms","start":"2026-04-07T05:51:29.430764Z","end":"2026-04-07T05:51:29.937928Z","steps":["trace[2082933616] 'agreement among raft nodes before linearized reading' (duration: 507.104854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:51:29.937974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:51:29.430745Z","time spent":"507.221395ms","remote":"127.0.0.1:35448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-04-07T05:51:30.542025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.223289ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:51:30.542109Z","caller":"traceutil/trace.go:171","msg":"trace[773656437] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"604.25257ms","start":"2026-04-07T05:51:29.937842Z","end":"2026-04-07T05:51:30.542095Z","steps":["trace[773656437] 'read index received' (duration: 116.857597ms)","trace[773656437] 'applied index is now lower than readState.Index' (duration: 487.394173ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:51:30.54221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111116226s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T05:51:30.542232Z","caller":"traceutil/trace.go:171","msg":"trace[1396529546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:621; }","duration":"1.111154367s","start":"2026-04-07T05:51:29.43107Z","end":"2026-04-07T05:51:30.542224Z","steps":["trace[1396529546] 'agreement among raft nodes before linearized reading' (duration: 1.111059225s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:51:30.542255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:51:29.431062Z","time spent":"1.111187149s","remote":"127.0.0.1:35476","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-07T05:51:30.542389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.978267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:51:30.542412Z","caller":"traceutil/trace.go:171","msg":"trace[1491800314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"817.056629ms","start":"2026-04-07T05:51:29.72535Z","end":"2026-04-07T05:51:30.542406Z","steps":["trace[1491800314] 'agreement among raft nodes before linearized reading' (duration: 817.023299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:51:30.542429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:51:29.725318Z","time spent":"817.10786ms","remote":"127.0.0.1:35198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T05:51:30.542636Z","caller":"traceutil/trace.go:171","msg":"trace[844059830] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"1.109892794s","start":"2026-04-07T05:51:29.432317Z","end":"2026-04-07T05:51:30.54221Z","steps":["trace[844059830] 'process raft request' (duration: 622.404719ms)","trace[844059830] 'compare' (duration: 487.132956ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:51:30.542916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:51:29.432287Z","time spent":"1.110526061s","remote":"127.0.0.1:35476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:51:30.559075Z","caller":"traceutil/trace.go:171","msg":"trace[2123023236] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"215.326947ms","start":"2026-04-07T05:51:30.343718Z","end":"2026-04-07T05:51:30.559045Z","steps":["trace[2123023236] 'process raft request' (duration: 214.645609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:51:31.739516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.472527ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:51:31.740212Z","caller":"traceutil/trace.go:171","msg":"trace[1226249333] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"1.112120814s","start":"2026-04-07T05:51:30.628047Z","end":"2026-04-07T05:51:31.740168Z","steps":["trace[1226249333] 'process raft request' (duration: 653.829554ms)","trace[1226249333] 'compare' (duration: 456.991205ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:51:31.740391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:51:30.628022Z","time spent":"1.112267278s","remote":"127.0.0.1:35448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T05:51:31.756172Z","caller":"traceutil/trace.go:171","msg":"trace[2008026206] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:643; }","duration":"321.513766ms","start":"2026-04-07T05:51:31.434638Z","end":"2026-04-07T05:51:31.756151Z","steps":["trace[2008026206] 'read index received' (duration: 282.029664ms)","trace[2008026206] 'applied index is now lower than readState.Index' (duration: 39.482582ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:51:31.756398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.756412ms","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-07T05:51:31.756551Z","caller":"traceutil/trace.go:171","msg":"trace[1211313448] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:624; }","duration":"321.949739ms","start":"2026-04-07T05:51:31.434589Z","end":"2026-04-07T05:51:31.756538Z","steps":["trace[1211313448] 'agreement among raft nodes before linearized reading' (duration: 321.696001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:51:31.756603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:51:31.434568Z","time spent":"322.02435ms","remote":"127.0.0.1:35476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T05:51:32.182978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.580461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:51:32.183164Z","caller":"traceutil/trace.go:171","msg":"trace[271590132] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:631; }","duration":"102.759426ms","start":"2026-04-07T05:51:32.08035Z","end":"2026-04-07T05:51:32.18311Z","steps":["trace[271590132] 'count revisions from in-memory index tree' (duration: 102.531371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:51:32.183448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.164278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:51:32.183558Z","caller":"traceutil/trace.go:171","msg":"trace[1781443745] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:631; }","duration":"103.27883ms","start":"2026-04-07T05:51:32.08024Z","end":"2026-04-07T05:51:32.183518Z","steps":["trace[1781443745] 'range keys from in-memory index tree' (duration: 102.997134ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:51:34.361491Z","caller":"traceutil/trace.go:171","msg":"trace[401547622] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"137.238505ms","start":"2026-04-07T05:51:34.224224Z","end":"2026-04-07T05:51:34.361463Z","steps":["trace[401547622] 'process raft request' (duration: 137.055629ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:51:34.414012Z","caller":"traceutil/trace.go:171","msg":"trace[306359331] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"139.493006ms","start":"2026-04-07T05:51:34.274482Z","end":"2026-04-07T05:51:34.413975Z","steps":["trace[306359331] 'process raft request' (duration: 135.615351ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:51:47.605788Z","caller":"traceutil/trace.go:171","msg":"trace[76724364] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:808; }","duration":"345.667867ms","start":"2026-04-07T05:51:47.260077Z","end":"2026-04-07T05:51:47.605744Z","steps":["trace[76724364] 'read index received' (duration: 345.167623ms)","trace[76724364] 'applied index is now lower than readState.Index' (duration: 498.584µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:51:47.605894Z","caller":"traceutil/trace.go:171","msg":"trace[1094824562] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"370.898956ms","start":"2026-04-07T05:51:47.23496Z","end":"2026-04-07T05:51:47.605859Z","steps":["trace[1094824562] 'process raft request' (duration: 370.506485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:51:47.606136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:51:47.234936Z","time spent":"371.03597ms","remote":"127.0.0.1:35448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:51:47.606142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.028576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-07T05:51:47.606206Z","caller":"traceutil/trace.go:171","msg":"trace[291455893] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:784; }","duration":"346.15151ms","start":"2026-04-07T05:51:47.260035Z","end":"2026-04-07T05:51:47.606186Z","steps":["trace[291455893] 'agreement among raft nodes before linearized reading' (duration: 345.898033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:51:47.606253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:51:47.260023Z","time spent":"346.219972ms","remote":"127.0.0.1:35476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-07T05:52:16.794664Z","caller":"traceutil/trace.go:171","msg":"trace[783153204] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"168.194779ms","start":"2026-04-07T05:52:16.626431Z","end":"2026-04-07T05:52:16.794626Z","steps":["trace[783153204] 'process raft request' (duration: 168.007853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:52:22.734359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.152557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T05:52:22.734511Z","caller":"traceutil/trace.go:171","msg":"trace[1658530939] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1006; }","duration":"264.353562ms","start":"2026-04-07T05:52:22.470138Z","end":"2026-04-07T05:52:22.734492Z","steps":["trace[1658530939] 'count revisions from in-memory index tree' (duration: 264.087345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:52:22.734565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.460052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-07T05:52:22.734655Z","caller":"traceutil/trace.go:171","msg":"trace[1188529153] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1006; }","duration":"486.605825ms","start":"2026-04-07T05:52:22.24803Z","end":"2026-04-07T05:52:22.734636Z","steps":["trace[1188529153] 'range keys from in-memory index tree' (duration: 486.239046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:52:22.734705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:52:22.248009Z","time spent":"486.687359ms","remote":"127.0.0.1:35476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-07T05:52:22.880012Z","caller":"traceutil/trace.go:171","msg":"trace[881328081] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"141.524111ms","start":"2026-04-07T05:52:22.738471Z","end":"2026-04-07T05:52:22.879995Z","steps":["trace[881328081] 'process raft request' (duration: 141.413528ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:52:23.177627Z","caller":"traceutil/trace.go:171","msg":"trace[1154502522] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1040; }","duration":"339.024ms","start":"2026-04-07T05:52:22.838586Z","end":"2026-04-07T05:52:23.17761Z","steps":["trace[1154502522] 'read index received' (duration: 41.384449ms)","trace[1154502522] 'applied index is now lower than readState.Index' (duration: 297.638851ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:52:23.177793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.199874ms","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-07T05:52:23.177829Z","caller":"traceutil/trace.go:171","msg":"trace[2056860593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1008; }","duration":"339.274726ms","start":"2026-04-07T05:52:22.838533Z","end":"2026-04-07T05:52:23.177808Z","steps":["trace[2056860593] 'agreement among raft nodes before linearized reading' (duration: 339.163173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:52:23.177855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:52:22.838516Z","time spent":"339.332997ms","remote":"127.0.0.1:35476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T05:52:23.17786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.151588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T05:52:23.177923Z","caller":"traceutil/trace.go:171","msg":"trace[613758993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1008; }","duration":"278.267942ms","start":"2026-04-07T05:52:22.899645Z","end":"2026-04-07T05:52:23.177913Z","steps":["trace[613758993] 'agreement among raft nodes before linearized reading' (duration: 278.071336ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:52:23.177675Z","caller":"traceutil/trace.go:171","msg":"trace[1591711934] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"364.948607ms","start":"2026-04-07T05:52:22.812702Z","end":"2026-04-07T05:52:23.177651Z","steps":["trace[1591711934] 'process raft request' (duration: 364.602547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:52:23.178358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:52:22.812682Z","time spent":"365.626265ms","remote":"127.0.0.1:35476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:52:46.141126Z","caller":"traceutil/trace.go:171","msg":"trace[959835952] linearizableReadLoop","detail":"{readStateIndex:1121; appliedIndex:1120; }","duration":"106.694301ms","start":"2026-04-07T05:52:46.034408Z","end":"2026-04-07T05:52:46.141103Z","steps":["trace[959835952] 'read index received' (duration: 106.551327ms)","trace[959835952] 'applied index is now lower than readState.Index' (duration: 141.624µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:52:46.141509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.077551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-07T05:52:46.141541Z","caller":"traceutil/trace.go:171","msg":"trace[1966863268] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1082; }","duration":"107.164604ms","start":"2026-04-07T05:52:46.034367Z","end":"2026-04-07T05:52:46.141532Z","steps":["trace[1966863268] 'agreement among raft nodes before linearized reading' (duration: 106.829085ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:52:46.34507Z","caller":"traceutil/trace.go:171","msg":"trace[1165025915] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"202.764461ms","start":"2026-04-07T05:52:46.142284Z","end":"2026-04-07T05:52:46.345048Z","steps":["trace[1165025915] 'process raft request' (duration: 202.617378ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:52:46.515872Z","caller":"traceutil/trace.go:171","msg":"trace[1014772735] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"370.450577ms","start":"2026-04-07T05:52:46.145364Z","end":"2026-04-07T05:52:46.515815Z","steps":["trace[1014772735] 'process raft request' (duration: 346.132263ms)","trace[1014772735] 'compare' (duration: 24.015185ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:52:46.515923Z","caller":"traceutil/trace.go:171","msg":"trace[1465547054] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1121; }","duration":"344.854738ms","start":"2026-04-07T05:52:46.170858Z","end":"2026-04-07T05:52:46.515713Z","steps":["trace[1465547054] 'read index received' (duration: 174.046168ms)","trace[1465547054] 'applied index is now lower than readState.Index' (duration: 170.80751ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:52:46.516039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:52:46.145352Z","time spent":"370.59091ms","remote":"127.0.0.1:35476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:52:46.516118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.24077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-07T05:52:46.516161Z","caller":"traceutil/trace.go:171","msg":"trace[546550004] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1084; }","duration":"168.317633ms","start":"2026-04-07T05:52:46.347834Z","end":"2026-04-07T05:52:46.516151Z","steps":["trace[546550004] 'agreement among raft nodes before linearized reading' (duration: 168.252741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:52:46.516067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.153656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T05:52:46.516389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.525186ms","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-07T05:52:46.516477Z","caller":"traceutil/trace.go:171","msg":"trace[1825458906] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1084; }","duration":"345.65923ms","start":"2026-04-07T05:52:46.170799Z","end":"2026-04-07T05:52:46.516458Z","steps":["trace[1825458906] 'agreement among raft nodes before linearized reading' (duration: 345.075465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:52:46.516518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:52:46.170779Z","time spent":"345.730683ms","remote":"127.0.0.1:35476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T05:52:46.516431Z","caller":"traceutil/trace.go:171","msg":"trace[1592727177] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1084; }","duration":"176.567497ms","start":"2026-04-07T05:52:46.339855Z","end":"2026-04-07T05:52:46.516422Z","steps":["trace[1592727177] 'agreement among raft nodes before linearized reading' (duration: 176.153926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:52:47.300783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.952875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-07T05:52:47.300871Z","caller":"traceutil/trace.go:171","msg":"trace[946835171] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1086; }","duration":"156.077329ms","start":"2026-04-07T05:52:47.144778Z","end":"2026-04-07T05:52:47.300855Z","steps":["trace[946835171] 'range keys from in-memory index tree' (duration: 155.581145ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:52:47.478087Z","caller":"traceutil/trace.go:171","msg":"trace[870028608] linearizableReadLoop","detail":"{readStateIndex:1127; appliedIndex:1126; }","duration":"134.978082ms","start":"2026-04-07T05:52:47.343087Z","end":"2026-04-07T05:52:47.478065Z","steps":["trace[870028608] 'read index received' (duration: 132.018342ms)","trace[870028608] 'applied index is now lower than readState.Index' (duration: 2.95878ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:52:47.478272Z","caller":"traceutil/trace.go:171","msg":"trace[618995575] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"173.686368ms","start":"2026-04-07T05:52:47.304543Z","end":"2026-04-07T05:52:47.478229Z","steps":["trace[618995575] 'process raft request' (duration: 170.626024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:52:47.478659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.421674ms","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-07T05:52:47.478902Z","caller":"traceutil/trace.go:171","msg":"trace[1993627403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1088; }","duration":"135.723614ms","start":"2026-04-07T05:52:47.343164Z","end":"2026-04-07T05:52:47.478887Z","steps":["trace[1993627403] 'agreement among raft nodes before linearized reading' (duration: 135.318473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:52:47.478666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.556687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T05:52:47.4792Z","caller":"traceutil/trace.go:171","msg":"trace[883020762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1088; }","duration":"136.128884ms","start":"2026-04-07T05:52:47.343059Z","end":"2026-04-07T05:52:47.479188Z","steps":["trace[883020762] 'agreement among raft nodes before linearized reading' (duration: 135.452456ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:53:01.020601Z","caller":"traceutil/trace.go:171","msg":"trace[1497620712] linearizableReadLoop","detail":"{readStateIndex:1225; appliedIndex:1224; }","duration":"379.03809ms","start":"2026-04-07T05:53:00.641545Z","end":"2026-04-07T05:53:01.020583Z","steps":["trace[1497620712] 'read index received' (duration: 378.844694ms)","trace[1497620712] 'applied index is now lower than readState.Index' (duration: 192.586µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:53:01.020703Z","caller":"traceutil/trace.go:171","msg":"trace[989056113] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"380.935462ms","start":"2026-04-07T05:53:00.639737Z","end":"2026-04-07T05:53:01.020672Z","steps":["trace[989056113] 'process raft request' (duration: 380.720547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:53:01.020786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.224466ms","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-07T05:53:01.020814Z","caller":"traceutil/trace.go:171","msg":"trace[979582634] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1182; }","duration":"379.286048ms","start":"2026-04-07T05:53:00.641519Z","end":"2026-04-07T05:53:01.020805Z","steps":["trace[979582634] 'agreement among raft nodes before linearized reading' (duration: 379.170925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:53:01.020838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:53:00.641509Z","time spent":"379.323588ms","remote":"127.0.0.1:35476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T05:53:01.020827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.074614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T05:53:01.020861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:53:00.639718Z","time spent":"381.061175ms","remote":"127.0.0.1:35476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:53:01.020908Z","caller":"traceutil/trace.go:171","msg":"trace[724354035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1182; }","duration":"297.193098ms","start":"2026-04-07T05:53:00.723692Z","end":"2026-04-07T05:53:01.020885Z","steps":["trace[724354035] 'agreement among raft nodes before linearized reading' (duration: 297.047065ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:53:17.416167Z","caller":"traceutil/trace.go:171","msg":"trace[608449554] linearizableReadLoop","detail":"{readStateIndex:1302; appliedIndex:1301; }","duration":"273.993186ms","start":"2026-04-07T05:53:17.142148Z","end":"2026-04-07T05:53:17.416141Z","steps":["trace[608449554] 'read index received' (duration: 273.817581ms)","trace[608449554] 'applied index is now lower than readState.Index' (duration: 174.045µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:53:17.416281Z","caller":"traceutil/trace.go:171","msg":"trace[770527110] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"282.167977ms","start":"2026-04-07T05:53:17.134079Z","end":"2026-04-07T05:53:17.416247Z","steps":["trace[770527110] 'process raft request' (duration: 281.863388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:53:17.416524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.973166ms","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-07T05:53:17.416605Z","caller":"traceutil/trace.go:171","msg":"trace[1643538256] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1255; }","duration":"257.074118ms","start":"2026-04-07T05:53:17.159489Z","end":"2026-04-07T05:53:17.416563Z","steps":["trace[1643538256] 'agreement among raft nodes before linearized reading' (duration: 256.807081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:53:17.416578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.407537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-04-07T05:53:17.419413Z","caller":"traceutil/trace.go:171","msg":"trace[636472869] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1255; }","duration":"277.088748ms","start":"2026-04-07T05:53:17.142116Z","end":"2026-04-07T05:53:17.419205Z","steps":["trace[636472869] 'agreement among raft nodes before linearized reading' (duration: 274.163789ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:53:17.602512Z","caller":"traceutil/trace.go:171","msg":"trace[1871403659] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"177.364814ms","start":"2026-04-07T05:53:17.425122Z","end":"2026-04-07T05:53:17.602487Z","steps":["trace[1871403659] 'process raft request' (duration: 177.16905ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:53:17.756407Z","caller":"traceutil/trace.go:171","msg":"trace[1576446926] linearizableReadLoop","detail":"{readStateIndex:1305; appliedIndex:1302; }","duration":"192.532444ms","start":"2026-04-07T05:53:17.563854Z","end":"2026-04-07T05:53:17.756386Z","steps":["trace[1576446926] 'read index received' (duration: 38.48198ms)","trace[1576446926] 'applied index is now lower than readState.Index' (duration: 154.049654ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:53:17.756507Z","caller":"traceutil/trace.go:171","msg":"trace[591898400] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"324.626844ms","start":"2026-04-07T05:53:17.431831Z","end":"2026-04-07T05:53:17.756458Z","steps":["trace[591898400] 'process raft request' (duration: 324.504711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:53:17.756831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:53:17.431823Z","time spent":"324.933303ms","remote":"127.0.0.1:35380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5457,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:53:17.756526Z","caller":"traceutil/trace.go:171","msg":"trace[1883227513] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"329.185978ms","start":"2026-04-07T05:53:17.427304Z","end":"2026-04-07T05:53:17.75649Z","steps":["trace[1883227513] 'process raft request' (duration: 270.897472ms)","trace[1883227513] 'compare' (duration: 58.000959ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:53:17.756975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:53:17.427288Z","time spent":"329.595699ms","remote":"127.0.0.1:35476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:53:17.756632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.759771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-6nxll\" ","response":"range_response_count:1 size:11061"} {"level":"info","ts":"2026-04-07T05:53:17.757063Z","caller":"traceutil/trace.go:171","msg":"trace[1242702619] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-6nxll; range_end:; response_count:1; response_revision:1258; }","duration":"193.241344ms","start":"2026-04-07T05:53:17.563802Z","end":"2026-04-07T05:53:17.757044Z","steps":["trace[1242702619] 'agreement among raft nodes before linearized reading' (duration: 192.692089ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:53:19.608589Z","caller":"traceutil/trace.go:171","msg":"trace[1494259635] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"180.194602ms","start":"2026-04-07T05:53:19.428358Z","end":"2026-04-07T05:53:19.608552Z","steps":["trace[1494259635] 'process raft request' (duration: 179.923224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:53:19.989043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.298176ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:53:19.989119Z","caller":"traceutil/trace.go:171","msg":"trace[2041163773] linearizableReadLoop","detail":"{readStateIndex:1318; appliedIndex:1317; }","duration":"205.322389ms","start":"2026-04-07T05:53:19.783781Z","end":"2026-04-07T05:53:19.989104Z","steps":["trace[2041163773] 'read index received' (duration: 46.024183ms)","trace[2041163773] 'applied index is now lower than readState.Index' (duration: 159.297146ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:53:19.989183Z","caller":"traceutil/trace.go:171","msg":"trace[1503926626] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"221.180479ms","start":"2026-04-07T05:53:19.767996Z","end":"2026-04-07T05:53:19.989177Z","steps":["trace[1503926626] 'process raft request' (duration: 61.683808ms)","trace[1503926626] 'compare' (duration: 159.125001ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:53:19.989333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.555786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-07T05:53:19.989351Z","caller":"traceutil/trace.go:171","msg":"trace[948338848] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1271; }","duration":"205.647489ms","start":"2026-04-07T05:53:19.783699Z","end":"2026-04-07T05:53:19.989346Z","steps":["trace[948338848] 'agreement among raft nodes before linearized reading' (duration: 205.559207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:53:19.989453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.65455ms","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-07T05:53:19.989469Z","caller":"traceutil/trace.go:171","msg":"trace[252906271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1271; }","duration":"174.727584ms","start":"2026-04-07T05:53:19.814737Z","end":"2026-04-07T05:53:19.989464Z","steps":["trace[252906271] 'agreement among raft nodes before linearized reading' (duration: 174.678502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:53:19.989529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.867167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T05:53:19.989608Z","caller":"traceutil/trace.go:171","msg":"trace[905938884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1271; }","duration":"174.992001ms","start":"2026-04-07T05:53:19.814597Z","end":"2026-04-07T05:53:19.989589Z","steps":["trace[905938884] 'agreement among raft nodes before linearized reading' (duration: 174.755625ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:53:21.454102Z","caller":"traceutil/trace.go:171","msg":"trace[481679628] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"132.284425ms","start":"2026-04-07T05:53:21.321787Z","end":"2026-04-07T05:53:21.454071Z","steps":["trace[481679628] 'process raft request' (duration: 70.946008ms)","trace[481679628] 'compare' (duration: 61.170253ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:53:31.692082Z","caller":"traceutil/trace.go:171","msg":"trace[623729188] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"189.999766ms","start":"2026-04-07T05:53:31.502048Z","end":"2026-04-07T05:53:31.692048Z","steps":["trace[623729188] 'process raft request' (duration: 189.723298ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:53:31.958296Z","caller":"traceutil/trace.go:171","msg":"trace[416105127] linearizableReadLoop","detail":"{readStateIndex:1403; appliedIndex:1402; }","duration":"234.002195ms","start":"2026-04-07T05:53:31.72426Z","end":"2026-04-07T05:53:31.958262Z","steps":["trace[416105127] 'read index received' (duration: 233.759088ms)","trace[416105127] 'applied index is now lower than readState.Index' (duration: 241.177µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:53:31.958436Z","caller":"traceutil/trace.go:171","msg":"trace[617101219] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"262.632059ms","start":"2026-04-07T05:53:31.695789Z","end":"2026-04-07T05:53:31.958421Z","steps":["trace[617101219] 'process raft request' (duration: 262.284609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:53:31.958813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.428557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:53:31.958981Z","caller":"traceutil/trace.go:171","msg":"trace[2001889563] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1353; }","duration":"234.760725ms","start":"2026-04-07T05:53:31.724197Z","end":"2026-04-07T05:53:31.958958Z","steps":["trace[2001889563] 'agreement among raft nodes before linearized reading' (duration: 234.315853ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:53:31.995298Z","caller":"traceutil/trace.go:171","msg":"trace[1618100397] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"116.656143ms","start":"2026-04-07T05:53:31.878603Z","end":"2026-04-07T05:53:31.995259Z","steps":["trace[1618100397] 'process raft request' (duration: 116.388207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:53:31.995326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.814725ms","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-07T05:53:31.995401Z","caller":"traceutil/trace.go:171","msg":"trace[237793487] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1354; }","duration":"245.944079ms","start":"2026-04-07T05:53:31.749439Z","end":"2026-04-07T05:53:31.995383Z","steps":["trace[237793487] 'agreement among raft nodes before linearized reading' (duration: 245.749853ms)"],"step_count":1}