{"level":"warn","ts":"2026-06-05T16:08:14.060011Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-05T16:08:14.060108Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.22: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.22:2380","--initial-cluster=instance=https://199.204.45.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.22: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-06-05T16:08:14.060191Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-05T16:08:14.060206Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-06-05T16:08:14.060228Z","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-06-05T16:08:14.060966Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"]} {"level":"info","ts":"2026-06-05T16:08:14.06106Z","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.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.22: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-06-05T16:08:14.068375Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.610873ms"} {"level":"info","ts":"2026-06-05T16:08:14.082605Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fed19d9291630e09","cluster-id":"6ebf1e01320cc017"} {"level":"info","ts":"2026-06-05T16:08:14.082698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=()"} {"level":"info","ts":"2026-06-05T16:08:14.082847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 0"} {"level":"info","ts":"2026-06-05T16:08:14.082867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fed19d9291630e09 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-05T16:08:14.082932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 1"} {"level":"info","ts":"2026-06-05T16:08:14.083128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"warn","ts":"2026-06-05T16:08:14.09292Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-05T16:08:14.095087Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-05T16:08:14.096392Z","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-06-05T16:08:14.098348Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fed19d9291630e09","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-05T16:08:14.098487Z","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-06-05T16:08:14.098597Z","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-06-05T16:08:14.098541Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fed19d9291630e09","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-05T16:08:14.098622Z","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-06-05T16:08:14.101958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"info","ts":"2026-06-05T16:08:14.102186Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","added-peer-id":"fed19d9291630e09","added-peer-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-06-05T16:08:14.104184Z","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-06-05T16:08:14.104416Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fed19d9291630e09","initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-05T16:08:14.104435Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-05T16:08:14.104532Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-06-05T16:08:14.104595Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-06-05T16:08:14.384296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 is starting a new election at term 1"} {"level":"info","ts":"2026-06-05T16:08:14.384422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-05T16:08:14.384464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgPreVoteResp from fed19d9291630e09 at term 1"} {"level":"info","ts":"2026-06-05T16:08:14.384497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became candidate at term 2"} {"level":"info","ts":"2026-06-05T16:08:14.384518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgVoteResp from fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-06-05T16:08:14.38454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became leader at term 2"} {"level":"info","ts":"2026-06-05T16:08:14.384561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fed19d9291630e09 elected leader fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-06-05T16:08:14.390974Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fed19d9291630e09","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.22:2379]}","request-path":"/0/members/fed19d9291630e09/attributes","cluster-id":"6ebf1e01320cc017","publish-timeout":"7s"} {"level":"info","ts":"2026-06-05T16:08:14.390985Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T16:08:14.390983Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-05T16:08:14.391156Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-05T16:08:14.391268Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-05T16:08:14.391294Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-05T16:08:14.392141Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T16:08:14.392231Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T16:08:14.392257Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T16:08:14.394445Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-05T16:08:14.397837Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.22:2379"} {"level":"warn","ts":"2026-06-05T16:08:20.388194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.640761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:08:20.388342Z","caller":"traceutil/trace.go:171","msg":"trace[495073041] linearizableReadLoop","detail":"{readStateIndex:294; appliedIndex:293; }","duration":"133.992371ms","start":"2026-06-05T16:08:20.254333Z","end":"2026-06-05T16:08:20.388325Z","steps":["trace[495073041] 'read index received' (duration: 57.671µs)","trace[495073041] 'applied index is now lower than readState.Index' (duration: 133.93344ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:08:20.388509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.171335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:08:20.388649Z","caller":"traceutil/trace.go:171","msg":"trace[356954483] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:286; }","duration":"134.354249ms","start":"2026-06-05T16:08:20.254281Z","end":"2026-06-05T16:08:20.388635Z","steps":["trace[356954483] 'agreement among raft nodes before linearized reading' (duration: 134.103843ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:08:20.388695Z","caller":"traceutil/trace.go:171","msg":"trace[750802447] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"390.180271ms","start":"2026-06-05T16:08:19.998441Z","end":"2026-06-05T16:08:20.388621Z","steps":["trace[750802447] 'process raft request' (duration: 105.596087ms)","trace[750802447] 'compare' (duration: 283.411386ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:08:20.38906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:08:19.998418Z","time spent":"390.475717ms","remote":"127.0.0.1:53876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":302,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-05T16:08:20.985019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.296965ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:08:20.985499Z","caller":"traceutil/trace.go:171","msg":"trace[1776578141] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"593.283361ms","start":"2026-06-05T16:08:20.392146Z","end":"2026-06-05T16:08:20.985429Z","steps":["trace[1776578141] 'process raft request' (duration: 295.472514ms)","trace[1776578141] 'compare' (duration: 296.947777ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:08:20.985602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:08:20.392128Z","time spent":"593.440394ms","remote":"127.0.0.1:53890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":435,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-05T16:08:21.356707Z","caller":"traceutil/trace.go:171","msg":"trace[399974730] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"963.83403ms","start":"2026-06-05T16:08:20.392837Z","end":"2026-06-05T16:08:21.356671Z","steps":["trace[399974730] 'process raft request' (duration: 963.470952ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:08:21.356776Z","caller":"traceutil/trace.go:171","msg":"trace[401736104] linearizableReadLoop","detail":"{readStateIndex:296; appliedIndex:294; }","duration":"949.773507ms","start":"2026-06-05T16:08:20.406972Z","end":"2026-06-05T16:08:21.356746Z","steps":["trace[401736104] 'read index received' (duration: 280.660594ms)","trace[401736104] 'applied index is now lower than readState.Index' (duration: 669.111633ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:08:21.356889Z","caller":"traceutil/trace.go:171","msg":"trace[1928190119] transaction","detail":"{read_only:false; number_of_response:0; response_revision:288; }","duration":"799.238486ms","start":"2026-06-05T16:08:20.557641Z","end":"2026-06-05T16:08:21.35688Z","steps":["trace[1928190119] 'process raft request' (duration: 798.952149ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:08:21.356897Z","caller":"traceutil/trace.go:171","msg":"trace[369691837] transaction","detail":"{read_only:false; number_of_response:0; response_revision:288; }","duration":"796.81411ms","start":"2026-06-05T16:08:20.560077Z","end":"2026-06-05T16:08:21.356891Z","steps":["trace[369691837] 'process raft request' (duration: 796.590695ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:08:21.356953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:08:20.560064Z","time spent":"796.871661ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-05T16:08:21.356954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:08:20.557616Z","time spent":"799.312107ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-05T16:08:21.356987Z","caller":"traceutil/trace.go:171","msg":"trace[225117014] transaction","detail":"{read_only:false; number_of_response:0; response_revision:288; }","duration":"796.785559ms","start":"2026-06-05T16:08:20.560192Z","end":"2026-06-05T16:08:21.356977Z","steps":["trace[225117014] 'process raft request' (duration: 796.528093ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:08:21.356843Z","caller":"traceutil/trace.go:171","msg":"trace[97309141] transaction","detail":"{read_only:false; number_of_response:0; response_revision:288; }","duration":"797.095555ms","start":"2026-06-05T16:08:20.559734Z","end":"2026-06-05T16:08:21.35683Z","steps":["trace[97309141] 'process raft request' (duration: 796.894161ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:08:21.357096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:08:20.559718Z","time spent":"797.352813ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-05T16:08:21.357104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:08:20.560183Z","time spent":"796.83654ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-05T16:08:21.357227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.246528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T16:08:21.35685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:08:20.392821Z","time spent":"963.960522ms","remote":"127.0.0.1:53810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-05T16:08:21.357268Z","caller":"traceutil/trace.go:171","msg":"trace[193117744] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:288; }","duration":"950.33393ms","start":"2026-06-05T16:08:20.406922Z","end":"2026-06-05T16:08:21.357256Z","steps":["trace[193117744] 'agreement among raft nodes before linearized reading' (duration: 950.229828ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:08:21.3573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:08:20.4069Z","time spent":"950.392811ms","remote":"127.0.0.1:53810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-05T16:08:21.357428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.41719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3468"} {"level":"warn","ts":"2026-06-05T16:08:21.357436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.782935ms","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-06-05T16:08:21.357463Z","caller":"traceutil/trace.go:171","msg":"trace[457072463] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:288; }","duration":"799.493012ms","start":"2026-06-05T16:08:20.557959Z","end":"2026-06-05T16:08:21.357452Z","steps":["trace[457072463] 'agreement among raft nodes before linearized reading' (duration: 799.42225ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:08:21.357463Z","caller":"traceutil/trace.go:171","msg":"trace[1615080431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:288; }","duration":"400.848067ms","start":"2026-06-05T16:08:20.956607Z","end":"2026-06-05T16:08:21.357455Z","steps":["trace[1615080431] 'agreement among raft nodes before linearized reading' (duration: 400.795875ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:08:21.357488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:08:20.557941Z","time spent":"799.540193ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3491,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-06-05T16:08:21.357491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:08:20.956583Z","time spent":"400.901507ms","remote":"127.0.0.1:53810","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-06-05T16:08:54.658698Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513969322362852,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-05T16:08:54.731428Z","caller":"traceutil/trace.go:171","msg":"trace[934925367] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"573.095555ms","start":"2026-06-05T16:08:54.158299Z","end":"2026-06-05T16:08:54.731394Z","steps":["trace[934925367] 'read index received' (duration: 572.370948ms)","trace[934925367] 'applied index is now lower than readState.Index' (duration: 723.567µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:08:54.731553Z","caller":"traceutil/trace.go:171","msg":"trace[530525866] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"598.726425ms","start":"2026-06-05T16:08:54.132792Z","end":"2026-06-05T16:08:54.731518Z","steps":["trace[530525866] 'process raft request' (duration: 598.142191ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:08:54.731673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.357091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-06-05T16:08:54.731713Z","caller":"traceutil/trace.go:171","msg":"trace[1554671677] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:604; }","duration":"573.437873ms","start":"2026-06-05T16:08:54.158262Z","end":"2026-06-05T16:08:54.731699Z","steps":["trace[1554671677] 'agreement among raft nodes before linearized reading' (duration: 573.279209ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:08:54.731749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:08:54.15824Z","time spent":"573.500495ms","remote":"127.0.0.1:53620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":1,"response size":7731,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-06-05T16:08:54.731976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:08:54.132745Z","time spent":"598.929499ms","remote":"127.0.0.1:53620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:08:55.096985Z","caller":"traceutil/trace.go:171","msg":"trace[751549005] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"312.328051ms","start":"2026-06-05T16:08:54.784633Z","end":"2026-06-05T16:08:55.096962Z","steps":["trace[751549005] 'read index received' (duration: 312.221919ms)","trace[751549005] 'applied index is now lower than readState.Index' (duration: 105.202µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:08:55.097114Z","caller":"traceutil/trace.go:171","msg":"trace[601143535] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"316.881479ms","start":"2026-06-05T16:08:54.780179Z","end":"2026-06-05T16:08:55.097061Z","steps":["trace[601143535] 'process raft request' (duration: 316.634413ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:08:55.097198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.543706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:9149"} {"level":"info","ts":"2026-06-05T16:08:55.097236Z","caller":"traceutil/trace.go:171","msg":"trace[743524651] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:605; }","duration":"312.620268ms","start":"2026-06-05T16:08:54.784603Z","end":"2026-06-05T16:08:55.097223Z","steps":["trace[743524651] 'agreement among raft nodes before linearized reading' (duration: 312.454985ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:08:55.097271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:08:54.784593Z","time spent":"312.669429ms","remote":"127.0.0.1:53620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9172,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io\" "} {"level":"warn","ts":"2026-06-05T16:08:55.097352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:08:54.780152Z","time spent":"317.055132ms","remote":"127.0.0.1:53620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:08:55.299645Z","caller":"traceutil/trace.go:171","msg":"trace[786373168] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"134.631352ms","start":"2026-06-05T16:08:55.164983Z","end":"2026-06-05T16:08:55.299615Z","steps":["trace[786373168] 'read index received' (duration: 133.626779ms)","trace[786373168] 'applied index is now lower than readState.Index' (duration: 992.302µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:08:55.299847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.864967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:74769"} {"level":"info","ts":"2026-06-05T16:08:55.299888Z","caller":"traceutil/trace.go:171","msg":"trace[1311511035] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:606; }","duration":"134.940779ms","start":"2026-06-05T16:08:55.164935Z","end":"2026-06-05T16:08:55.299876Z","steps":["trace[1311511035] 'agreement among raft nodes before linearized reading' (duration: 134.777955ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:08:55.300094Z","caller":"traceutil/trace.go:171","msg":"trace[1537257297] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"155.747096ms","start":"2026-06-05T16:08:55.144289Z","end":"2026-06-05T16:08:55.300037Z","steps":["trace[1537257297] 'process raft request' (duration: 154.490407ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:09:01.735886Z","caller":"traceutil/trace.go:171","msg":"trace[783976307] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"144.078173ms","start":"2026-06-05T16:09:01.591784Z","end":"2026-06-05T16:09:01.735862Z","steps":["trace[783976307] 'read index received' (duration: 143.885838ms)","trace[783976307] 'applied index is now lower than readState.Index' (duration: 191.324µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:09:01.735989Z","caller":"traceutil/trace.go:171","msg":"trace[642901238] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"150.023962ms","start":"2026-06-05T16:09:01.585931Z","end":"2026-06-05T16:09:01.735955Z","steps":["trace[642901238] 'process raft request' (duration: 149.795036ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:09:01.736125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.317749ms","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-06-05T16:09:01.736168Z","caller":"traceutil/trace.go:171","msg":"trace[801512292] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:648; }","duration":"144.40545ms","start":"2026-06-05T16:09:01.591749Z","end":"2026-06-05T16:09:01.736154Z","steps":["trace[801512292] 'agreement among raft nodes before linearized reading' (duration: 144.251766ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:09:01.736222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.527403ms","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-06-05T16:09:01.736266Z","caller":"traceutil/trace.go:171","msg":"trace[1930572025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:648; }","duration":"141.606726ms","start":"2026-06-05T16:09:01.594648Z","end":"2026-06-05T16:09:01.736255Z","steps":["trace[1930572025] 'agreement among raft nodes before linearized reading' (duration: 141.441652ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:09:01.916757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.233697ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:09:01.916986Z","caller":"traceutil/trace.go:171","msg":"trace[1621899606] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"175.951859ms","start":"2026-06-05T16:09:01.741014Z","end":"2026-06-05T16:09:01.916966Z","steps":["trace[1621899606] 'process raft request' (duration: 60.434825ms)","trace[1621899606] 'compare' (duration: 114.91943ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:09:01.9171Z","caller":"traceutil/trace.go:171","msg":"trace[896554650] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"167.136783ms","start":"2026-06-05T16:09:01.749944Z","end":"2026-06-05T16:09:01.917081Z","steps":["trace[896554650] 'process raft request' (duration: 166.919848ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:09:02.847002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.989728ms","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-06-05T16:09:02.847095Z","caller":"traceutil/trace.go:171","msg":"trace[671733268] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:651; }","duration":"780.11304ms","start":"2026-06-05T16:09:02.066963Z","end":"2026-06-05T16:09:02.847076Z","steps":["trace[671733268] 'range keys from in-memory index tree' (duration: 779.743952ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:09:02.847134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:09:02.066945Z","time spent":"780.180842ms","remote":"127.0.0.1:53810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-05T16:09:02.847002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.866388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:09:02.847318Z","caller":"traceutil/trace.go:171","msg":"trace[1707564918] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:651; }","duration":"209.216618ms","start":"2026-06-05T16:09:02.638077Z","end":"2026-06-05T16:09:02.847293Z","steps":["trace[1707564918] 'range keys from in-memory index tree' (duration: 208.782917ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:09:03.969882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.475371ms","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-06-05T16:09:03.969967Z","caller":"traceutil/trace.go:171","msg":"trace[1868427788] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:657; }","duration":"214.602333ms","start":"2026-06-05T16:09:03.755346Z","end":"2026-06-05T16:09:03.969949Z","steps":["trace[1868427788] 'range keys from in-memory index tree' (duration: 214.091882ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:09:18.999097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.773007ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:09:18.99932Z","caller":"traceutil/trace.go:171","msg":"trace[738944504] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:834; }","duration":"698.633403ms","start":"2026-06-05T16:09:18.300671Z","end":"2026-06-05T16:09:18.999304Z","steps":["trace[738944504] 'read index received' (duration: 128.541408ms)","trace[738944504] 'applied index is now lower than readState.Index' (duration: 570.091275ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:09:18.999303Z","caller":"traceutil/trace.go:171","msg":"trace[1543454111] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"952.399173ms","start":"2026-06-05T16:09:18.046874Z","end":"2026-06-05T16:09:18.999273Z","steps":["trace[1543454111] 'process raft request' (duration: 382.340419ms)","trace[1543454111] 'compare' (duration: 569.677095ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:09:18.999563Z","caller":"traceutil/trace.go:171","msg":"trace[381867564] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"952.636209ms","start":"2026-06-05T16:09:18.046915Z","end":"2026-06-05T16:09:18.999551Z","steps":["trace[381867564] 'process raft request' (duration: 952.295591ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:09:18.999644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:09:18.046906Z","time spent":"952.69922ms","remote":"127.0.0.1:53810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:09:18.999923Z","caller":"traceutil/trace.go:171","msg":"trace[1581231113] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"924.778307ms","start":"2026-06-05T16:09:18.075117Z","end":"2026-06-05T16:09:18.999896Z","steps":["trace[1581231113] 'process raft request' (duration: 924.146312ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:09:18.99995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.279207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-06-05T16:09:19.000004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:09:18.046853Z","time spent":"952.567838ms","remote":"127.0.0.1:53810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:09:19.000028Z","caller":"traceutil/trace.go:171","msg":"trace[1285565676] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:803; }","duration":"699.3975ms","start":"2026-06-05T16:09:18.300609Z","end":"2026-06-05T16:09:19.000006Z","steps":["trace[1285565676] 'agreement among raft nodes before linearized reading' (duration: 699.197836ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:09:19.000081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:09:18.300563Z","time spent":"699.508114ms","remote":"127.0.0.1:53810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-05T16:09:19.000089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:09:18.075087Z","time spent":"924.92119ms","remote":"127.0.0.1:53810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:09:19.001049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.356479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:09:19.001165Z","caller":"traceutil/trace.go:171","msg":"trace[1857099016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:804; }","duration":"361.520123ms","start":"2026-06-05T16:09:18.639627Z","end":"2026-06-05T16:09:19.001147Z","steps":["trace[1857099016] 'agreement among raft nodes before linearized reading' (duration: 361.318528ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:09:19.001215Z","caller":"traceutil/trace.go:171","msg":"trace[1589528808] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"243.808367ms","start":"2026-06-05T16:09:18.757388Z","end":"2026-06-05T16:09:19.001196Z","steps":["trace[1589528808] 'process raft request' (duration: 243.443147ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:09:19.001254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:09:18.639607Z","time spent":"361.623805ms","remote":"127.0.0.1:53576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-05T16:09:54.695158Z","caller":"traceutil/trace.go:171","msg":"trace[1377275918] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"734.476185ms","start":"2026-06-05T16:09:53.960655Z","end":"2026-06-05T16:09:54.695131Z","steps":["trace[1377275918] 'process raft request' (duration: 734.290881ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:09:54.695311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:09:53.960634Z","time spent":"734.606009ms","remote":"127.0.0.1:53810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:09:54.72945Z","caller":"traceutil/trace.go:171","msg":"trace[1343187515] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"489.382434ms","start":"2026-06-05T16:09:54.240037Z","end":"2026-06-05T16:09:54.729419Z","steps":["trace[1343187515] 'process raft request' (duration: 489.23524ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:09:54.729657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:09:54.240013Z","time spent":"489.520237ms","remote":"127.0.0.1:53810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:10:15.554685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.046129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-05T16:10:15.554692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.027818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-05T16:10:15.554768Z","caller":"traceutil/trace.go:171","msg":"trace[857966127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1094; }","duration":"193.170731ms","start":"2026-06-05T16:10:15.361586Z","end":"2026-06-05T16:10:15.554756Z","steps":["trace[857966127] 'range keys from in-memory index tree' (duration: 192.861324ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:10:15.554792Z","caller":"traceutil/trace.go:171","msg":"trace[1055601060] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1094; }","duration":"193.183991ms","start":"2026-06-05T16:10:15.361586Z","end":"2026-06-05T16:10:15.554769Z","steps":["trace[1055601060] 'range keys from in-memory index tree' (duration: 192.827174ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:15.555066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.589103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-05T16:10:15.555108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.290325ms","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-06-05T16:10:15.555159Z","caller":"traceutil/trace.go:171","msg":"trace[1366632855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1094; }","duration":"289.756158ms","start":"2026-06-05T16:10:15.265382Z","end":"2026-06-05T16:10:15.555139Z","steps":["trace[1366632855] 'range keys from in-memory index tree' (duration: 289.405959ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:10:15.555178Z","caller":"traceutil/trace.go:171","msg":"trace[817401345] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1094; }","duration":"252.405859ms","start":"2026-06-05T16:10:15.302755Z","end":"2026-06-05T16:10:15.555161Z","steps":["trace[817401345] 'range keys from in-memory index tree' (duration: 252.103341ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:17.790274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.280626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-05T16:10:17.790359Z","caller":"traceutil/trace.go:171","msg":"trace[1191396732] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1101; }","duration":"169.413069ms","start":"2026-06-05T16:10:17.620922Z","end":"2026-06-05T16:10:17.790335Z","steps":["trace[1191396732] 'range keys from in-memory index tree' (duration: 169.009659ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:17.790384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.819706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:10:17.790445Z","caller":"traceutil/trace.go:171","msg":"trace[90890997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1101; }","duration":"151.925798ms","start":"2026-06-05T16:10:17.638503Z","end":"2026-06-05T16:10:17.790429Z","steps":["trace[90890997] 'range keys from in-memory index tree' (duration: 151.692922ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:17.790618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.594733ms","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-06-05T16:10:17.790699Z","caller":"traceutil/trace.go:171","msg":"trace[1420378915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1101; }","duration":"169.736477ms","start":"2026-06-05T16:10:17.620944Z","end":"2026-06-05T16:10:17.79068Z","steps":["trace[1420378915] 'range keys from in-memory index tree' (duration: 169.329517ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:17.790727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.85131ms","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-06-05T16:10:17.790803Z","caller":"traceutil/trace.go:171","msg":"trace[878987399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1101; }","duration":"169.968063ms","start":"2026-06-05T16:10:17.620816Z","end":"2026-06-05T16:10:17.790784Z","steps":["trace[878987399] 'range keys from in-memory index tree' (duration: 169.690185ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:10:17.912424Z","caller":"traceutil/trace.go:171","msg":"trace[395431113] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"117.473969ms","start":"2026-06-05T16:10:17.794934Z","end":"2026-06-05T16:10:17.912408Z","steps":["trace[395431113] 'process raft request' (duration: 117.382767ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:10:17.944332Z","caller":"traceutil/trace.go:171","msg":"trace[68678430] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"149.151012ms","start":"2026-06-05T16:10:17.795155Z","end":"2026-06-05T16:10:17.944306Z","steps":["trace[68678430] 'process raft request' (duration: 149.09269ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:10:17.945347Z","caller":"traceutil/trace.go:171","msg":"trace[320609347] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"150.211148ms","start":"2026-06-05T16:10:17.795108Z","end":"2026-06-05T16:10:17.945319Z","steps":["trace[320609347] 'process raft request' (duration: 149.012288ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:10:18.103962Z","caller":"traceutil/trace.go:171","msg":"trace[202155796] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"113.246526ms","start":"2026-06-05T16:10:17.990692Z","end":"2026-06-05T16:10:18.103939Z","steps":["trace[202155796] 'process raft request' (duration: 96.495653ms)","trace[202155796] 'compare' (duration: 16.633791ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:10:34.381525Z","caller":"traceutil/trace.go:171","msg":"trace[2091331945] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"370.778907ms","start":"2026-06-05T16:10:34.010727Z","end":"2026-06-05T16:10:34.381506Z","steps":["trace[2091331945] 'process raft request' (duration: 370.670025ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:34.381633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:34.010717Z","time spent":"370.86205ms","remote":"127.0.0.1:53810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:10:34.401387Z","caller":"traceutil/trace.go:171","msg":"trace[1310404475] linearizableReadLoop","detail":"{readStateIndex:1259; appliedIndex:1257; }","duration":"314.463142ms","start":"2026-06-05T16:10:34.086904Z","end":"2026-06-05T16:10:34.401367Z","steps":["trace[1310404475] 'read index received' (duration: 294.4519ms)","trace[1310404475] 'applied index is now lower than readState.Index' (duration: 20.010522ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:10:34.401543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.620515ms","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-06-05T16:10:34.401525Z","caller":"traceutil/trace.go:171","msg":"trace[1897549686] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"389.821046ms","start":"2026-06-05T16:10:34.011667Z","end":"2026-06-05T16:10:34.401488Z","steps":["trace[1897549686] 'process raft request' (duration: 389.592931ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:34.401697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:34.011638Z","time spent":"389.97569ms","remote":"127.0.0.1:53810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:10:34.401569Z","caller":"traceutil/trace.go:171","msg":"trace[888891571] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1208; }","duration":"314.697257ms","start":"2026-06-05T16:10:34.086863Z","end":"2026-06-05T16:10:34.40156Z","steps":["trace[888891571] 'agreement among raft nodes before linearized reading' (duration: 314.593635ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:34.401783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:34.086845Z","time spent":"314.917043ms","remote":"127.0.0.1:53810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-05T16:10:34.658099Z","caller":"traceutil/trace.go:171","msg":"trace[1295455096] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"251.819474ms","start":"2026-06-05T16:10:34.406233Z","end":"2026-06-05T16:10:34.658053Z","steps":["trace[1295455096] 'process raft request' (duration: 227.98454ms)","trace[1295455096] 'compare' (duration: 23.603809ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:10:47.889581Z","caller":"traceutil/trace.go:171","msg":"trace[2053642692] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"143.809889ms","start":"2026-06-05T16:10:47.745753Z","end":"2026-06-05T16:10:47.889563Z","steps":["trace[2053642692] 'process raft request' (duration: 143.691127ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:10:50.695486Z","caller":"traceutil/trace.go:171","msg":"trace[385265722] linearizableReadLoop","detail":"{readStateIndex:1321; appliedIndex:1320; }","duration":"223.014618ms","start":"2026-06-05T16:10:50.472448Z","end":"2026-06-05T16:10:50.695463Z","steps":["trace[385265722] 'read index received' (duration: 222.855134ms)","trace[385265722] 'applied index is now lower than readState.Index' (duration: 158.624µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:10:50.695993Z","caller":"traceutil/trace.go:171","msg":"trace[1002410520] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"240.329201ms","start":"2026-06-05T16:10:50.455635Z","end":"2026-06-05T16:10:50.695964Z","steps":["trace[1002410520] 'process raft request' (duration: 239.695056ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:50.696172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.714026ms","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-06-05T16:10:50.696221Z","caller":"traceutil/trace.go:171","msg":"trace[67996401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1267; }","duration":"223.806127ms","start":"2026-06-05T16:10:50.472403Z","end":"2026-06-05T16:10:50.696209Z","steps":["trace[67996401] 'agreement among raft nodes before linearized reading' (duration: 223.672224ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:10:51.080141Z","caller":"traceutil/trace.go:171","msg":"trace[2101792575] linearizableReadLoop","detail":"{readStateIndex:1322; appliedIndex:1321; }","duration":"336.884933ms","start":"2026-06-05T16:10:50.743226Z","end":"2026-06-05T16:10:51.080111Z","steps":["trace[2101792575] 'read index received' (duration: 313.772106ms)","trace[2101792575] 'applied index is now lower than readState.Index' (duration: 23.111077ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:10:51.080204Z","caller":"traceutil/trace.go:171","msg":"trace[1323601628] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"380.655939ms","start":"2026-06-05T16:10:50.699516Z","end":"2026-06-05T16:10:51.080171Z","steps":["trace[1323601628] 'process raft request' (duration: 357.457081ms)","trace[1323601628] 'compare' (duration: 23.015584ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:10:51.0803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.064157ms","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-06-05T16:10:51.080353Z","caller":"traceutil/trace.go:171","msg":"trace[957190134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1268; }","duration":"337.1595ms","start":"2026-06-05T16:10:50.743183Z","end":"2026-06-05T16:10:51.080342Z","steps":["trace[957190134] 'agreement among raft nodes before linearized reading' (duration: 337.050578ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:51.080387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:50.743159Z","time spent":"337.223592ms","remote":"127.0.0.1:53810","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-06-05T16:10:51.080364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:50.699505Z","time spent":"380.760742ms","remote":"127.0.0.1:53810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:10:51.379182Z","caller":"traceutil/trace.go:171","msg":"trace[667783311] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1322; }","duration":"298.952183ms","start":"2026-06-05T16:10:51.080211Z","end":"2026-06-05T16:10:51.379163Z","steps":["trace[667783311] 'read index received' (duration: 298.704776ms)","trace[667783311] 'applied index is now lower than readState.Index' (duration: 246.667µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:10:51.379293Z","caller":"traceutil/trace.go:171","msg":"trace[2026963352] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"338.674585ms","start":"2026-06-05T16:10:51.040583Z","end":"2026-06-05T16:10:51.379257Z","steps":["trace[2026963352] 'process raft request' (duration: 338.528782ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:51.3796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:51.040572Z","time spent":"338.967061ms","remote":"127.0.0.1:53810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:10:51.3795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.724554ms","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-06-05T16:10:51.3802Z","caller":"traceutil/trace.go:171","msg":"trace[407577043] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1270; }","duration":"304.465831ms","start":"2026-06-05T16:10:51.075707Z","end":"2026-06-05T16:10:51.380173Z","steps":["trace[407577043] 'agreement among raft nodes before linearized reading' (duration: 303.615251ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:10:51.379269Z","caller":"traceutil/trace.go:171","msg":"trace[1709826530] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"346.949897ms","start":"2026-06-05T16:10:51.032284Z","end":"2026-06-05T16:10:51.379234Z","steps":["trace[1709826530] 'process raft request' (duration: 346.67992ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:51.380286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:51.075684Z","time spent":"304.572353ms","remote":"127.0.0.1:53810","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-06-05T16:10:51.380443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:51.032255Z","time spent":"348.073202ms","remote":"127.0.0.1:53810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:10:51.386749Z","caller":"traceutil/trace.go:171","msg":"trace[884802561] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"302.917284ms","start":"2026-06-05T16:10:51.083814Z","end":"2026-06-05T16:10:51.386732Z","steps":["trace[884802561] 'process raft request' (duration: 302.807962ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:51.386861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:51.083782Z","time spent":"303.017267ms","remote":"127.0.0.1:53810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:10:52.134564Z","caller":"traceutil/trace.go:171","msg":"trace[1199833419] linearizableReadLoop","detail":"{readStateIndex:1329; appliedIndex:1328; }","duration":"231.918586ms","start":"2026-06-05T16:10:51.902622Z","end":"2026-06-05T16:10:52.13454Z","steps":["trace[1199833419] 'read index received' (duration: 231.793993ms)","trace[1199833419] 'applied index is now lower than readState.Index' (duration: 123.373µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:10:52.134683Z","caller":"traceutil/trace.go:171","msg":"trace[1056417891] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"260.98245ms","start":"2026-06-05T16:10:51.87362Z","end":"2026-06-05T16:10:52.134603Z","steps":["trace[1056417891] 'process raft request' (duration: 260.763135ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:52.134788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.979354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:10:52.134822Z","caller":"traceutil/trace.go:171","msg":"trace[819475383] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1275; }","duration":"231.042485ms","start":"2026-06-05T16:10:51.903769Z","end":"2026-06-05T16:10:52.134812Z","steps":["trace[819475383] 'agreement among raft nodes before linearized reading' (duration: 230.938092ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:52.134895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.244793ms","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-06-05T16:10:52.134975Z","caller":"traceutil/trace.go:171","msg":"trace[901324461] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1275; }","duration":"232.361456ms","start":"2026-06-05T16:10:51.902596Z","end":"2026-06-05T16:10:52.134957Z","steps":["trace[901324461] 'agreement among raft nodes before linearized reading' (duration: 232.064169ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:52.473974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.95146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-05T16:10:52.474271Z","caller":"traceutil/trace.go:171","msg":"trace[522548724] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"334.291843ms","start":"2026-06-05T16:10:52.139952Z","end":"2026-06-05T16:10:52.474244Z","steps":["trace[522548724] 'process raft request' (duration: 334.237732ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:52.474374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:52.13992Z","time spent":"334.402865ms","remote":"127.0.0.1:53810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:10:52.474525Z","caller":"traceutil/trace.go:171","msg":"trace[2011476582] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"336.132556ms","start":"2026-06-05T16:10:52.138357Z","end":"2026-06-05T16:10:52.474489Z","steps":["trace[2011476582] 'process raft request' (duration: 195.569361ms)","trace[2011476582] 'compare' (duration: 139.761765ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:10:52.474687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:52.13833Z","time spent":"336.278369ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-05T16:10:52.474764Z","caller":"traceutil/trace.go:171","msg":"trace[1734693930] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"334.861016ms","start":"2026-06-05T16:10:52.139872Z","end":"2026-06-05T16:10:52.474733Z","steps":["trace[1734693930] 'process raft request' (duration: 334.262131ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:52.47487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:52.139857Z","time spent":"334.961399ms","remote":"127.0.0.1:53810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-05T16:10:52.764399Z","caller":"traceutil/trace.go:171","msg":"trace[1478712118] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"284.898726ms","start":"2026-06-05T16:10:52.47945Z","end":"2026-06-05T16:10:52.764348Z","steps":["trace[1478712118] 'process raft request' (duration: 268.528016ms)","trace[1478712118] 'compare' (duration: 15.882669ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:10:52.945236Z","caller":"traceutil/trace.go:171","msg":"trace[1243148526] linearizableReadLoop","detail":"{readStateIndex:1334; appliedIndex:1332; }","duration":"305.792942ms","start":"2026-06-05T16:10:52.639425Z","end":"2026-06-05T16:10:52.945218Z","steps":["trace[1243148526] 'read index received' (duration: 108.561591ms)","trace[1243148526] 'applied index is now lower than readState.Index' (duration: 197.230231ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:10:52.945478Z","caller":"traceutil/trace.go:171","msg":"trace[1569208501] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"462.765008ms","start":"2026-06-05T16:10:52.482701Z","end":"2026-06-05T16:10:52.945466Z","steps":["trace[1569208501] 'process raft request' (duration: 422.350509ms)","trace[1569208501] 'compare' (duration: 40.002718ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:10:52.94556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:52.482674Z","time spent":"462.829938ms","remote":"127.0.0.1:53810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:10:52.945758Z","caller":"traceutil/trace.go:171","msg":"trace[1499824769] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"212.192468ms","start":"2026-06-05T16:10:52.733558Z","end":"2026-06-05T16:10:52.945751Z","steps":["trace[1499824769] 'process raft request' (duration: 211.616475ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:52.945869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.448717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T16:10:52.945891Z","caller":"traceutil/trace.go:171","msg":"trace[1628708658] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1281; }","duration":"306.505628ms","start":"2026-06-05T16:10:52.639379Z","end":"2026-06-05T16:10:52.945884Z","steps":["trace[1628708658] 'agreement among raft nodes before linearized reading' (duration: 306.467477ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:52.945911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:52.63936Z","time spent":"306.545849ms","remote":"127.0.0.1:53576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-05T16:10:52.946073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.46806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-05T16:10:52.9461Z","caller":"traceutil/trace.go:171","msg":"trace[500401594] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1281; }","duration":"245.532382ms","start":"2026-06-05T16:10:52.70056Z","end":"2026-06-05T16:10:52.946092Z","steps":["trace[500401594] 'agreement among raft nodes before linearized reading' (duration: 245.45136ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:53.767428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.517702ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-05T16:10:53.767705Z","caller":"traceutil/trace.go:171","msg":"trace[746450231] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1337; }","duration":"376.597865ms","start":"2026-06-05T16:10:53.39109Z","end":"2026-06-05T16:10:53.767688Z","steps":["trace[746450231] 'read index received' (duration: 257.878008ms)","trace[746450231] 'applied index is now lower than readState.Index' (duration: 118.716747ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:10:53.76811Z","caller":"traceutil/trace.go:171","msg":"trace[457034865] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"407.606085ms","start":"2026-06-05T16:10:53.360487Z","end":"2026-06-05T16:10:53.768093Z","steps":["trace[457034865] 'process raft request' (duration: 407.044003ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:53.768217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:53.360467Z","time spent":"407.684387ms","remote":"127.0.0.1:53810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:10:53.768232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.980638ms","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-06-05T16:10:53.768272Z","caller":"traceutil/trace.go:171","msg":"trace[106029266] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1285; }","duration":"366.06946ms","start":"2026-06-05T16:10:53.402192Z","end":"2026-06-05T16:10:53.768261Z","steps":["trace[106029266] 'agreement among raft nodes before linearized reading' (duration: 365.928557ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:53.768303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:53.402165Z","time spent":"366.130762ms","remote":"127.0.0.1:53810","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":"info","ts":"2026-06-05T16:10:53.768382Z","caller":"traceutil/trace.go:171","msg":"trace[1662157875] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"382.723807ms","start":"2026-06-05T16:10:53.385648Z","end":"2026-06-05T16:10:53.768372Z","steps":["trace[1662157875] 'process raft request' (duration: 381.99509ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:53.768451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:53.385616Z","time spent":"382.790329ms","remote":"127.0.0.1:53810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T16:10:53.768645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.562458ms","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-06-05T16:10:53.76869Z","caller":"traceutil/trace.go:171","msg":"trace[1174813540] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1285; }","duration":"377.665871ms","start":"2026-06-05T16:10:53.391011Z","end":"2026-06-05T16:10:53.768677Z","steps":["trace[1174813540] 'agreement among raft nodes before linearized reading' (duration: 377.550817ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:10:53.768731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:10:53.390993Z","time spent":"377.724432ms","remote":"127.0.0.1:53810","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":"info","ts":"2026-06-05T16:10:53.957696Z","caller":"traceutil/trace.go:171","msg":"trace[2052880018] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1341; }","duration":"142.392007ms","start":"2026-06-05T16:10:53.815288Z","end":"2026-06-05T16:10:53.95768Z","steps":["trace[2052880018] 'read index received' (duration: 83.896338ms)","trace[2052880018] 'applied index is now lower than readState.Index' (duration: 58.495069ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:10:53.957854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.564761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T16:10:53.957852Z","caller":"traceutil/trace.go:171","msg":"trace[938651671] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"182.500799ms","start":"2026-06-05T16:10:53.775321Z","end":"2026-06-05T16:10:53.957822Z","steps":["trace[938651671] 'process raft request' (duration: 123.912568ms)","trace[938651671] 'compare' (duration: 58.169731ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:10:53.957891Z","caller":"traceutil/trace.go:171","msg":"trace[169677567] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1287; }","duration":"142.672174ms","start":"2026-06-05T16:10:53.815212Z","end":"2026-06-05T16:10:53.957884Z","steps":["trace[169677567] 'agreement among raft nodes before linearized reading' (duration: 142.598862ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:11:02.811443Z","caller":"traceutil/trace.go:171","msg":"trace[1233986416] linearizableReadLoop","detail":"{readStateIndex:1419; appliedIndex:1418; }","duration":"113.239859ms","start":"2026-06-05T16:11:02.698176Z","end":"2026-06-05T16:11:02.811416Z","steps":["trace[1233986416] 'read index received' (duration: 113.174437ms)","trace[1233986416] 'applied index is now lower than readState.Index' (duration: 64.222µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T16:11:02.811478Z","caller":"traceutil/trace.go:171","msg":"trace[1003564417] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"242.148882ms","start":"2026-06-05T16:11:02.569293Z","end":"2026-06-05T16:11:02.811441Z","steps":["trace[1003564417] 'process raft request' (duration: 241.986909ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:11:02.811666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.473906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-06-05T16:11:02.81182Z","caller":"traceutil/trace.go:171","msg":"trace[212496930] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1363; }","duration":"113.546128ms","start":"2026-06-05T16:11:02.698164Z","end":"2026-06-05T16:11:02.81171Z","steps":["trace[212496930] 'agreement among raft nodes before linearized reading' (duration: 113.367284ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:11:03.536156Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513969322364581,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-05T16:11:03.714104Z","caller":"traceutil/trace.go:171","msg":"trace[858832568] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"745.746949ms","start":"2026-06-05T16:11:02.968334Z","end":"2026-06-05T16:11:03.714081Z","steps":["trace[858832568] 'process raft request' (duration: 744.004538ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:11:03.714228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:11:02.968303Z","time spent":"745.853291ms","remote":"127.0.0.1:53810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T16:11:03.717591Z","caller":"traceutil/trace.go:171","msg":"trace[910640079] linearizableReadLoop","detail":"{readStateIndex:1422; appliedIndex:1420; }","duration":"682.148271ms","start":"2026-06-05T16:11:03.035421Z","end":"2026-06-05T16:11:03.717569Z","steps":["trace[910640079] 'read index received' (duration: 676.870869ms)","trace[910640079] 'applied index is now lower than readState.Index' (duration: 5.275962ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T16:11:03.717747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.334026ms","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-06-05T16:11:03.717786Z","caller":"traceutil/trace.go:171","msg":"trace[1898366883] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1365; }","duration":"682.419248ms","start":"2026-06-05T16:11:03.035358Z","end":"2026-06-05T16:11:03.717778Z","steps":["trace[1898366883] 'agreement among raft nodes before linearized reading' (duration: 682.292235ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:11:03.717814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:11:03.035336Z","time spent":"682.472379ms","remote":"127.0.0.1:53810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-05T16:11:03.717941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.007897ms","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-06-05T16:11:03.717997Z","caller":"traceutil/trace.go:171","msg":"trace[868808932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1365; }","duration":"542.101139ms","start":"2026-06-05T16:11:03.175884Z","end":"2026-06-05T16:11:03.717985Z","steps":["trace[868808932] 'agreement among raft nodes before linearized reading' (duration: 541.933975ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T16:11:03.71803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T16:11:03.175865Z","time spent":"542.1572ms","remote":"127.0.0.1:53810","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-06-05T16:11:03.718043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.899213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-06-05T16:11:03.718067Z","caller":"traceutil/trace.go:171","msg":"trace[88805398] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1365; }","duration":"190.962235ms","start":"2026-06-05T16:11:03.527097Z","end":"2026-06-05T16:11:03.718059Z","steps":["trace[88805398] 'agreement among raft nodes before linearized reading' (duration: 190.856702ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T16:14:58.043675Z","caller":"traceutil/trace.go:171","msg":"trace[1751143052] transaction","detail":"{read_only:false; response_revision:2469; number_of_response:1; }","duration":"122.539423ms","start":"2026-06-05T16:14:57.921105Z","end":"2026-06-05T16:14:58.043645Z","steps":["trace[1751143052] 'process raft request' (duration: 122.175205ms)"],"step_count":1}