{"level":"warn","ts":"2026-07-07T20:05:52.520339Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-07T20:05:52.520412Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.96:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.96:2380","--initial-cluster=instance=https://199.19.213.96:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.96:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.96: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-07-07T20:05:52.520478Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-07T20:05:52.520489Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.96:2380"]} {"level":"info","ts":"2026-07-07T20:05:52.520507Z","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-07-07T20:05:52.521255Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.96:2379"]} {"level":"info","ts":"2026-07-07T20:05:52.52136Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.96:2380"],"listen-peer-urls":["https://199.19.213.96:2380"],"advertise-client-urls":["https://199.19.213.96:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.96:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.96: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-07-07T20:05:52.524912Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.275823ms"} {"level":"info","ts":"2026-07-07T20:05:52.530343Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"59b38ffafb07452b","cluster-id":"f6b359b7e8f93d01"} {"level":"info","ts":"2026-07-07T20:05:52.530432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b switched to configuration voters=()"} {"level":"info","ts":"2026-07-07T20:05:52.530455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b became follower at term 0"} {"level":"info","ts":"2026-07-07T20:05:52.530477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 59b38ffafb07452b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-07T20:05:52.530492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b became follower at term 1"} {"level":"info","ts":"2026-07-07T20:05:52.530529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b switched to configuration voters=(6463668198322947371)"} {"level":"warn","ts":"2026-07-07T20:05:52.53502Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-07T20:05:52.536161Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-07T20:05:52.537212Z","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-07-07T20:05:52.53905Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"59b38ffafb07452b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-07T20:05:52.539319Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"59b38ffafb07452b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-07T20:05:52.539635Z","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-07-07T20:05:52.539752Z","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-07-07T20:05:52.539763Z","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-07-07T20:05:52.541785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b switched to configuration voters=(6463668198322947371)"} {"level":"info","ts":"2026-07-07T20:05:52.541895Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f6b359b7e8f93d01","local-member-id":"59b38ffafb07452b","added-peer-id":"59b38ffafb07452b","added-peer-peer-urls":["https://199.19.213.96:2380"]} {"level":"info","ts":"2026-07-07T20:05:52.543243Z","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-07-07T20:05:52.543434Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"59b38ffafb07452b","initial-advertise-peer-urls":["https://199.19.213.96:2380"],"listen-peer-urls":["https://199.19.213.96:2380"],"advertise-client-urls":["https://199.19.213.96:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.96:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-07T20:05:52.543459Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-07T20:05:52.543566Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.96:2380"} {"level":"info","ts":"2026-07-07T20:05:52.543578Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.96:2380"} {"level":"info","ts":"2026-07-07T20:05:52.830781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b is starting a new election at term 1"} {"level":"info","ts":"2026-07-07T20:05:52.830855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b became pre-candidate at term 1"} {"level":"info","ts":"2026-07-07T20:05:52.830938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b received MsgPreVoteResp from 59b38ffafb07452b at term 1"} {"level":"info","ts":"2026-07-07T20:05:52.830992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b became candidate at term 2"} {"level":"info","ts":"2026-07-07T20:05:52.83101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b received MsgVoteResp from 59b38ffafb07452b at term 2"} {"level":"info","ts":"2026-07-07T20:05:52.831052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b became leader at term 2"} {"level":"info","ts":"2026-07-07T20:05:52.83107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 59b38ffafb07452b elected leader 59b38ffafb07452b at term 2"} {"level":"info","ts":"2026-07-07T20:05:52.831931Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T20:05:52.832699Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"59b38ffafb07452b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.96:2379]}","request-path":"/0/members/59b38ffafb07452b/attributes","cluster-id":"f6b359b7e8f93d01","publish-timeout":"7s"} {"level":"info","ts":"2026-07-07T20:05:52.832704Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-07T20:05:52.832906Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-07T20:05:52.83306Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f6b359b7e8f93d01","local-member-id":"59b38ffafb07452b","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T20:05:52.833182Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T20:05:52.833241Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T20:05:52.833309Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-07T20:05:52.83338Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-07T20:05:52.836433Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-07T20:05:52.837043Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.96:2379"} {"level":"warn","ts":"2026-07-07T20:05:57.83941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.133932ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-07T20:05:57.839794Z","caller":"traceutil/trace.go:171","msg":"trace[975357975] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"576.019106ms","start":"2026-07-07T20:05:57.263734Z","end":"2026-07-07T20:05:57.839753Z","steps":["trace[975357975] 'process raft request' (duration: 131.997632ms)","trace[975357975] 'compare' (duration: 442.980338ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:05:57.839881Z","caller":"traceutil/trace.go:171","msg":"trace[1037248135] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"504.261856ms","start":"2026-07-07T20:05:57.335599Z","end":"2026-07-07T20:05:57.839861Z","steps":["trace[1037248135] 'process raft request' (duration: 503.963628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:05:57.840128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:05:57.263715Z","time spent":"576.337154ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":597,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T20:05:57.839848Z","caller":"traceutil/trace.go:171","msg":"trace[811660180] linearizableReadLoop","detail":"{readStateIndex:286; appliedIndex:285; }","duration":"542.696174ms","start":"2026-07-07T20:05:57.297128Z","end":"2026-07-07T20:05:57.839824Z","steps":["trace[811660180] 'read index received' (duration: 98.583527ms)","trace[811660180] 'applied index is now lower than readState.Index' (duration: 444.110656ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T20:05:57.839985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.845029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T20:05:57.84029Z","caller":"traceutil/trace.go:171","msg":"trace[1312031660] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pv-protection-controller; range_end:; response_count:0; response_revision:281; }","duration":"543.186716ms","start":"2026-07-07T20:05:57.29709Z","end":"2026-07-07T20:05:57.840277Z","steps":["trace[1312031660] 'agreement among raft nodes before linearized reading' (duration: 542.801727ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:05:57.840323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:05:57.297078Z","time spent":"543.236518ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" "} {"level":"warn","ts":"2026-07-07T20:05:57.840434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:05:57.335585Z","time spent":"504.592934ms","remote":"127.0.0.1:44806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T20:05:57.866978Z","caller":"traceutil/trace.go:171","msg":"trace[234968067] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"409.422253ms","start":"2026-07-07T20:05:57.457528Z","end":"2026-07-07T20:05:57.86695Z","steps":["trace[234968067] 'process raft request' (duration: 409.211617ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:05:57.866996Z","caller":"traceutil/trace.go:171","msg":"trace[77194499] transaction","detail":"{read_only:false; number_of_response:0; response_revision:283; }","duration":"407.440462ms","start":"2026-07-07T20:05:57.459512Z","end":"2026-07-07T20:05:57.866953Z","steps":["trace[77194499] 'process raft request' (duration: 407.310239ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:05:57.866992Z","caller":"traceutil/trace.go:171","msg":"trace[4257552] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"408.642122ms","start":"2026-07-07T20:05:57.458284Z","end":"2026-07-07T20:05:57.866926Z","steps":["trace[4257552] 'process raft request' (duration: 408.511418ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:05:57.867104Z","caller":"traceutil/trace.go:171","msg":"trace[1789536660] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"407.563305ms","start":"2026-07-07T20:05:57.459511Z","end":"2026-07-07T20:05:57.867074Z","steps":["trace[1789536660] 'process raft request' (duration: 407.325069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:05:57.86719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:05:57.4595Z","time spent":"407.591045ms","remote":"127.0.0.1:44914","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-07-07T20:05:57.867205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:05:57.4595Z","time spent":"407.659307ms","remote":"127.0.0.1:44914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T20:05:57.867264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:05:57.45827Z","time spent":"408.856167ms","remote":"127.0.0.1:44914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5877,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T20:05:57.867302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.802959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T20:05:57.867359Z","caller":"traceutil/trace.go:171","msg":"trace[839440600] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:284; }","duration":"529.895592ms","start":"2026-07-07T20:05:57.33745Z","end":"2026-07-07T20:05:57.867346Z","steps":["trace[839440600] 'agreement among raft nodes before linearized reading' (duration: 529.762609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:05:57.867376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.016425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-07-07T20:05:57.867407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:05:57.337431Z","time spent":"529.963033ms","remote":"127.0.0.1:45008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-07-07T20:05:57.8674Z","caller":"traceutil/trace.go:171","msg":"trace[1873939651] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:284; }","duration":"530.069086ms","start":"2026-07-07T20:05:57.337323Z","end":"2026-07-07T20:05:57.867392Z","steps":["trace[1873939651] 'agreement among raft nodes before linearized reading' (duration: 530.005505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:05:57.867525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:05:57.337315Z","time spent":"530.18832ms","remote":"127.0.0.1:45154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-07-07T20:05:57.867493Z","caller":"traceutil/trace.go:171","msg":"trace[1409593468] transaction","detail":"{read_only:false; number_of_response:0; response_revision:281; }","duration":"425.658732ms","start":"2026-07-07T20:05:57.441223Z","end":"2026-07-07T20:05:57.866882Z","steps":["trace[1409593468] 'process raft request' (duration: 425.456097ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:05:57.867759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:05:57.457503Z","time spent":"409.514795ms","remote":"127.0.0.1:44914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T20:05:57.867654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:05:57.441198Z","time spent":"426.403591ms","remote":"127.0.0.1:44912","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-07-07T20:05:57.866911Z","caller":"traceutil/trace.go:171","msg":"trace[1302644134] transaction","detail":"{read_only:false; number_of_response:0; response_revision:284; }","duration":"406.346935ms","start":"2026-07-07T20:05:57.460538Z","end":"2026-07-07T20:05:57.866885Z","steps":["trace[1302644134] 'process raft request' (duration: 406.320735ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:05:57.867967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:05:57.460495Z","time spent":"407.430992ms","remote":"127.0.0.1:44914","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-07-07T20:05:57.948637Z","caller":"traceutil/trace.go:171","msg":"trace[1433834973] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"103.671827ms","start":"2026-07-07T20:05:57.844947Z","end":"2026-07-07T20:05:57.948619Z","steps":["trace[1433834973] 'process raft request' (duration: 93.862299ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:05:57.94882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.556238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-07T20:05:57.94889Z","caller":"traceutil/trace.go:171","msg":"trace[930553043] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:286; }","duration":"104.667261ms","start":"2026-07-07T20:05:57.844207Z","end":"2026-07-07T20:05:57.948874Z","steps":["trace[930553043] 'agreement among raft nodes before linearized reading' (duration: 104.484947ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:05:57.949208Z","caller":"traceutil/trace.go:171","msg":"trace[1569242743] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"103.79124ms","start":"2026-07-07T20:05:57.845404Z","end":"2026-07-07T20:05:57.949195Z","steps":["trace[1569242743] 'process raft request' (duration: 103.124062ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:06:28.64322Z","caller":"traceutil/trace.go:171","msg":"trace[1309938997] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"225.843069ms","start":"2026-07-07T20:06:28.41731Z","end":"2026-07-07T20:06:28.643154Z","steps":["trace[1309938997] 'read index received' (duration: 224.942226ms)","trace[1309938997] 'applied index is now lower than readState.Index' (duration: 899.543µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:06:28.643862Z","caller":"traceutil/trace.go:171","msg":"trace[1788634215] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"251.118858ms","start":"2026-07-07T20:06:28.392646Z","end":"2026-07-07T20:06:28.643765Z","steps":["trace[1788634215] 'process raft request' (duration: 250.124153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:06:28.643935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.595589ms","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-07-07T20:06:28.644046Z","caller":"traceutil/trace.go:171","msg":"trace[1115893426] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:587; }","duration":"226.769912ms","start":"2026-07-07T20:06:28.417254Z","end":"2026-07-07T20:06:28.644024Z","steps":["trace[1115893426] 'agreement among raft nodes before linearized reading' (duration: 226.527197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:06:28.645149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.633065ms","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-07-07T20:06:28.645204Z","caller":"traceutil/trace.go:171","msg":"trace[282708891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:588; }","duration":"227.753287ms","start":"2026-07-07T20:06:28.417439Z","end":"2026-07-07T20:06:28.645193Z","steps":["trace[282708891] 'agreement among raft nodes before linearized reading' (duration: 227.607644ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:06:28.645258Z","caller":"traceutil/trace.go:171","msg":"trace[677710376] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"119.962198ms","start":"2026-07-07T20:06:28.525275Z","end":"2026-07-07T20:06:28.645237Z","steps":["trace[677710376] 'process raft request' (duration: 119.682291ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:06:28.645388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.568811ms","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-07-07T20:06:28.64542Z","caller":"traceutil/trace.go:171","msg":"trace[668099248] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:588; }","duration":"137.644483ms","start":"2026-07-07T20:06:28.507768Z","end":"2026-07-07T20:06:28.645413Z","steps":["trace[668099248] 'agreement among raft nodes before linearized reading' (duration: 137.589222ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:06:33.00797Z","caller":"traceutil/trace.go:171","msg":"trace[1246225242] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"104.711242ms","start":"2026-07-07T20:06:32.903237Z","end":"2026-07-07T20:06:33.007948Z","steps":["trace[1246225242] 'process raft request' (duration: 104.165358ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:06:33.185403Z","caller":"traceutil/trace.go:171","msg":"trace[1763978205] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"127.818745ms","start":"2026-07-07T20:06:33.057553Z","end":"2026-07-07T20:06:33.185372Z","steps":["trace[1763978205] 'process raft request' (duration: 99.781358ms)","trace[1763978205] 'compare' (duration: 27.911684ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T20:06:33.80192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.041481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hhq2x\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-07T20:06:33.802048Z","caller":"traceutil/trace.go:171","msg":"trace[503213466] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hhq2x; range_end:; response_count:1; response_revision:623; }","duration":"342.218555ms","start":"2026-07-07T20:06:33.459802Z","end":"2026-07-07T20:06:33.802021Z","steps":["trace[503213466] 'range keys from in-memory index tree' (duration: 341.903078ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:06:33.802108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:06:33.459778Z","time spent":"342.316238ms","remote":"127.0.0.1:44914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-hhq2x\" "} {"level":"info","ts":"2026-07-07T20:06:33.80772Z","caller":"traceutil/trace.go:171","msg":"trace[396683258] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"217.296724ms","start":"2026-07-07T20:06:33.590393Z","end":"2026-07-07T20:06:33.807689Z","steps":["trace[396683258] 'process raft request' (duration: 216.664408ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:06:48.932049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.006712ms","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-07-07T20:06:48.932151Z","caller":"traceutil/trace.go:171","msg":"trace[2038024912] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:789; }","duration":"180.165906ms","start":"2026-07-07T20:06:48.751958Z","end":"2026-07-07T20:06:48.932124Z","steps":["trace[2038024912] 'range keys from in-memory index tree' (duration: 179.853878ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:06:48.932049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.410192ms","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-07-07T20:06:48.932419Z","caller":"traceutil/trace.go:171","msg":"trace[258477271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:789; }","duration":"182.814392ms","start":"2026-07-07T20:06:48.749562Z","end":"2026-07-07T20:06:48.932376Z","steps":["trace[258477271] 'range keys from in-memory index tree' (duration: 182.222107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:06:48.932571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.585915ms","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-07-07T20:06:48.93262Z","caller":"traceutil/trace.go:171","msg":"trace[1857251790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:789; }","duration":"180.658637ms","start":"2026-07-07T20:06:48.751949Z","end":"2026-07-07T20:06:48.932607Z","steps":["trace[1857251790] 'range keys from in-memory index tree' (duration: 179.848087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:07:22.658381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.468717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T20:07:22.658473Z","caller":"traceutil/trace.go:171","msg":"trace[603256285] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:999; }","duration":"347.616192ms","start":"2026-07-07T20:07:22.310837Z","end":"2026-07-07T20:07:22.658453Z","steps":["trace[603256285] 'count revisions from in-memory index tree' (duration: 347.358485ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:07:22.658519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:07:22.310816Z","time spent":"347.690893ms","remote":"127.0.0.1:45270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-07T20:07:22.6588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.452703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T20:07:22.658883Z","caller":"traceutil/trace.go:171","msg":"trace[669875] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:999; }","duration":"698.583976ms","start":"2026-07-07T20:07:21.960279Z","end":"2026-07-07T20:07:22.658863Z","steps":["trace[669875] 'count revisions from in-memory index tree' (duration: 698.151266ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:07:22.658927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:07:21.96026Z","time spent":"698.655588ms","remote":"127.0.0.1:44892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-07-07T20:07:22.659175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.08736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"info","ts":"2026-07-07T20:07:22.659201Z","caller":"traceutil/trace.go:171","msg":"trace[1540167629] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:999; }","duration":"656.135291ms","start":"2026-07-07T20:07:22.003059Z","end":"2026-07-07T20:07:22.659194Z","steps":["trace[1540167629] 'range keys from in-memory index tree' (duration: 656.019748ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:07:22.659223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:07:22.003048Z","time spent":"656.170452ms","remote":"127.0.0.1:44934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":247,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" "} {"level":"warn","ts":"2026-07-07T20:07:22.659397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.072683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T20:07:22.659427Z","caller":"traceutil/trace.go:171","msg":"trace[1852731597] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:999; }","duration":"432.142564ms","start":"2026-07-07T20:07:22.227278Z","end":"2026-07-07T20:07:22.659421Z","steps":["trace[1852731597] 'count revisions from in-memory index tree' (duration: 431.998281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:07:22.659447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:07:22.227258Z","time spent":"432.183695ms","remote":"127.0.0.1:45056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-07T20:07:23.961125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.655863ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T20:07:23.961228Z","caller":"traceutil/trace.go:171","msg":"trace[260338384] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1037; }","duration":"864.77195ms","start":"2026-07-07T20:07:23.096441Z","end":"2026-07-07T20:07:23.961212Z","steps":["trace[260338384] 'read index received' (duration: 424.773169ms)","trace[260338384] 'applied index is now lower than readState.Index' (duration: 439.997661ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:07:23.961354Z","caller":"traceutil/trace.go:171","msg":"trace[1051358487] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"1.207874489s","start":"2026-07-07T20:07:22.753443Z","end":"2026-07-07T20:07:23.961318Z","steps":["trace[1051358487] 'process raft request' (duration: 767.820246ms)","trace[1051358487] 'compare' (duration: 439.309004ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T20:07:23.961485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.015137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-07T20:07:23.961518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:07:22.753428Z","time spent":"1.208009412s","remote":"127.0.0.1:44914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T20:07:23.961559Z","caller":"traceutil/trace.go:171","msg":"trace[1191018591] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1001; }","duration":"865.156541ms","start":"2026-07-07T20:07:23.096383Z","end":"2026-07-07T20:07:23.96154Z","steps":["trace[1191018591] 'agreement among raft nodes before linearized reading' (duration: 864.920775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:07:23.961601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:07:23.09636Z","time spent":"865.231972ms","remote":"127.0.0.1:45008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-07T20:07:24.525724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.537128ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T20:07:24.525802Z","caller":"traceutil/trace.go:171","msg":"trace[1223315541] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1038; }","duration":"564.53497ms","start":"2026-07-07T20:07:23.961253Z","end":"2026-07-07T20:07:24.525788Z","steps":["trace[1223315541] 'read index received' (duration: 269.870598ms)","trace[1223315541] 'applied index is now lower than readState.Index' (duration: 294.663462ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:07:24.525943Z","caller":"traceutil/trace.go:171","msg":"trace[1130392956] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"776.166295ms","start":"2026-07-07T20:07:23.749768Z","end":"2026-07-07T20:07:24.525935Z","steps":["trace[1130392956] 'process raft request' (duration: 481.34857ms)","trace[1130392956] 'compare' (duration: 294.443736ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T20:07:24.525984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:07:23.749744Z","time spent":"776.218286ms","remote":"127.0.0.1:45008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T20:07:24.526078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.353493016s","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-07-07T20:07:24.526145Z","caller":"traceutil/trace.go:171","msg":"trace[629937202] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1002; }","duration":"1.353598529s","start":"2026-07-07T20:07:23.172513Z","end":"2026-07-07T20:07:24.526132Z","steps":["trace[629937202] 'agreement among raft nodes before linearized reading' (duration: 1.353413615s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:07:24.526172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:07:23.172497Z","time spent":"1.353668951s","remote":"127.0.0.1:45008","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-07-07T20:07:24.526209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.424645653s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-07T20:07:24.526229Z","caller":"traceutil/trace.go:171","msg":"trace[380280962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1002; }","duration":"1.424685283s","start":"2026-07-07T20:07:23.101538Z","end":"2026-07-07T20:07:24.526223Z","steps":["trace[380280962] 'agreement among raft nodes before linearized reading' (duration: 1.424623932s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:07:24.52623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.427424363s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-07-07T20:07:24.526249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:07:23.101527Z","time spent":"1.424718934s","remote":"127.0.0.1:45008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-07T20:07:24.526277Z","caller":"traceutil/trace.go:171","msg":"trace[1781496265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1002; }","duration":"1.427518505s","start":"2026-07-07T20:07:23.098745Z","end":"2026-07-07T20:07:24.526263Z","steps":["trace[1781496265] 'agreement among raft nodes before linearized reading' (duration: 1.427346991s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:07:24.526314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066931277s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T20:07:24.526311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:07:23.098723Z","time spent":"1.427580327s","remote":"127.0.0.1:45008","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":"info","ts":"2026-07-07T20:07:24.526335Z","caller":"traceutil/trace.go:171","msg":"trace[1870299358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1002; }","duration":"1.066980359s","start":"2026-07-07T20:07:23.459348Z","end":"2026-07-07T20:07:24.526329Z","steps":["trace[1870299358] 'agreement among raft nodes before linearized reading' (duration: 1.066943518s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:07:24.526369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:07:23.45933Z","time spent":"1.067035012s","remote":"127.0.0.1:44724","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-07-07T20:07:24.5265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.272484551s","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-07-07T20:07:24.526549Z","caller":"traceutil/trace.go:171","msg":"trace[575621481] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1002; }","duration":"1.272566114s","start":"2026-07-07T20:07:23.25397Z","end":"2026-07-07T20:07:24.526536Z","steps":["trace[575621481] 'agreement among raft nodes before linearized reading' (duration: 1.27239692s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:07:24.526622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:07:23.253963Z","time spent":"1.272625145s","remote":"127.0.0.1:45008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-07T20:07:24.608669Z","caller":"traceutil/trace.go:171","msg":"trace[1451203946] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1002; }","duration":"645.502523ms","start":"2026-07-07T20:07:23.963152Z","end":"2026-07-07T20:07:24.608654Z","steps":["trace[1451203946] 'process raft request' (duration: 645.328259ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:07:24.608723Z","caller":"traceutil/trace.go:171","msg":"trace[1980909961] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"643.088292ms","start":"2026-07-07T20:07:23.965596Z","end":"2026-07-07T20:07:24.608685Z","steps":["trace[1980909961] 'process raft request' (duration: 642.965469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:07:24.608806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:07:23.963113Z","time spent":"645.599316ms","remote":"127.0.0.1:44914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80,"response count":0,"response size":4174,"request content":"compare: success: > failure: >"} {"level":"warn","ts":"2026-07-07T20:07:24.608815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.715067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T20:07:24.608875Z","caller":"traceutil/trace.go:171","msg":"trace[1174081793] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1003; }","duration":"424.832361ms","start":"2026-07-07T20:07:24.184027Z","end":"2026-07-07T20:07:24.608859Z","steps":["trace[1174081793] 'agreement among raft nodes before linearized reading' (duration: 424.668957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:07:24.608935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:07:24.184005Z","time spent":"424.914703ms","remote":"127.0.0.1:45184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-07-07T20:07:24.608981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.8296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-07T20:07:24.608944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:07:23.965574Z","time spent":"643.223656ms","remote":"127.0.0.1:45008","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-07-07T20:07:24.609006Z","caller":"traceutil/trace.go:171","msg":"trace[1771247077] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1003; }","duration":"584.893461ms","start":"2026-07-07T20:07:24.024105Z","end":"2026-07-07T20:07:24.608999Z","steps":["trace[1771247077] 'agreement among raft nodes before linearized reading' (duration: 584.84163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:07:24.609213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:07:24.02409Z","time spent":"585.115667ms","remote":"127.0.0.1:45200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-07-07T20:07:55.315531Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4984252502055525238,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T20:07:55.816221Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4984252502055525238,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T20:07:56.317029Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4984252502055525238,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T20:07:56.817942Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4984252502055525238,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T20:07:57.318759Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4984252502055525238,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T20:07:57.462186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000048625s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-07T20:07:57.462387Z","caller":"traceutil/trace.go:171","msg":"trace[801946630] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000290681s","start":"2026-07-07T20:07:55.462017Z","end":"2026-07-07T20:07:57.462308Z","steps":["trace[801946630] 'agreement among raft nodes before linearized reading' (duration: 2.000098307s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:07:57.4625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:07:55.46198Z","time spent":"2.000502106s","remote":"127.0.0.1:44724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-07T20:07:57.81904Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4984252502055525238,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T20:07:58.194048Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.558488446s","expected-duration":"1s"} {"level":"info","ts":"2026-07-07T20:07:58.195057Z","caller":"traceutil/trace.go:171","msg":"trace[1866683572] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"3.559610604s","start":"2026-07-07T20:07:54.635417Z","end":"2026-07-07T20:07:58.195027Z","steps":["trace[1866683572] 'process raft request' (duration: 3.559092041s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:07:58.195941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:07:54.635391Z","time spent":"3.560423344s","remote":"127.0.0.1:45008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T20:07:58.200261Z","caller":"traceutil/trace.go:171","msg":"trace[1388818422] linearizableReadLoop","detail":"{readStateIndex:1148; appliedIndex:1146; }","duration":"3.385206361s","start":"2026-07-07T20:07:54.815032Z","end":"2026-07-07T20:07:58.200238Z","steps":["trace[1388818422] 'read index received' (duration: 3.379429837s)","trace[1388818422] 'applied index is now lower than readState.Index' (duration: 5.775774ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:07:58.200365Z","caller":"traceutil/trace.go:171","msg":"trace[1814155586] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"1.694742378s","start":"2026-07-07T20:07:56.505563Z","end":"2026-07-07T20:07:58.200306Z","steps":["trace[1814155586] 'process raft request' (duration: 1.694552313s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:07:58.200488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.364774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T20:07:58.200529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.35406562s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.96\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-07T20:07:58.200551Z","caller":"traceutil/trace.go:171","msg":"trace[736009170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1104; }","duration":"737.472436ms","start":"2026-07-07T20:07:57.463062Z","end":"2026-07-07T20:07:58.200534Z","steps":["trace[736009170] 'agreement among raft nodes before linearized reading' (duration: 737.379733ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:07:58.200568Z","caller":"traceutil/trace.go:171","msg":"trace[688991813] range","detail":"{range_begin:/registry/masterleases/199.19.213.96; range_end:; response_count:1; response_revision:1104; }","duration":"2.354160833s","start":"2026-07-07T20:07:55.846395Z","end":"2026-07-07T20:07:58.200556Z","steps":["trace[688991813] 'agreement among raft nodes before linearized reading' (duration: 2.354042359s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:07:58.200536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:07:56.505532Z","time spent":"1.694913762s","remote":"127.0.0.1:45008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T20:07:58.200596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:07:57.463035Z","time spent":"737.553638ms","remote":"127.0.0.1:44740","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-07-07T20:07:58.200602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:07:55.846367Z","time spent":"2.354227704s","remote":"127.0.0.1:44766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.96\" "} {"level":"warn","ts":"2026-07-07T20:07:58.20061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.25688762s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T20:07:58.200658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.352829922s","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-07-07T20:07:58.200695Z","caller":"traceutil/trace.go:171","msg":"trace[1508917517] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1104; }","duration":"2.257044463s","start":"2026-07-07T20:07:55.943633Z","end":"2026-07-07T20:07:58.200677Z","steps":["trace[1508917517] 'agreement among raft nodes before linearized reading' (duration: 2.256866179s)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:07:58.200753Z","caller":"traceutil/trace.go:171","msg":"trace[2058097368] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1104; }","duration":"3.353018597s","start":"2026-07-07T20:07:54.847715Z","end":"2026-07-07T20:07:58.200733Z","steps":["trace[2058097368] 'agreement among raft nodes before linearized reading' (duration: 3.352715539s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:07:58.200758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:07:55.943587Z","time spent":"2.257159606s","remote":"127.0.0.1:44868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-07-07T20:07:58.200801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:07:54.847681Z","time spent":"3.353112798s","remote":"127.0.0.1:45008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-07T20:07:58.200919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.335438677s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-07T20:07:58.200928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.344688848s","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-07-07T20:07:58.200954Z","caller":"traceutil/trace.go:171","msg":"trace[1206834850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1104; }","duration":"3.335488018s","start":"2026-07-07T20:07:54.865456Z","end":"2026-07-07T20:07:58.200944Z","steps":["trace[1206834850] 'agreement among raft nodes before linearized reading' (duration: 3.335374085s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:07:58.200985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:07:54.865449Z","time spent":"3.33552955s","remote":"127.0.0.1:45008","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-07-07T20:07:58.200989Z","caller":"traceutil/trace.go:171","msg":"trace[74990235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1104; }","duration":"3.344824091s","start":"2026-07-07T20:07:54.856149Z","end":"2026-07-07T20:07:58.200973Z","steps":["trace[74990235] 'agreement among raft nodes before linearized reading' (duration: 3.344630957s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:07:58.201009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.351700443s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-07T20:07:58.201022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:07:54.856131Z","time spent":"3.344885152s","remote":"127.0.0.1:45008","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":"info","ts":"2026-07-07T20:07:58.201044Z","caller":"traceutil/trace.go:171","msg":"trace[637818822] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1104; }","duration":"3.351755095s","start":"2026-07-07T20:07:54.849279Z","end":"2026-07-07T20:07:58.201034Z","steps":["trace[637818822] 'agreement among raft nodes before linearized reading' (duration: 3.351634632s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:07:58.201079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:07:54.849269Z","time spent":"3.351803016s","remote":"127.0.0.1:45008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-07T20:07:58.201158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.386135025s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-07T20:07:58.201196Z","caller":"traceutil/trace.go:171","msg":"trace[760078368] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1104; }","duration":"3.386211796s","start":"2026-07-07T20:07:54.814975Z","end":"2026-07-07T20:07:58.201186Z","steps":["trace[760078368] 'agreement among raft nodes before linearized reading' (duration: 3.385363484s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:07:58.201221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:07:54.814954Z","time spent":"3.386261618s","remote":"127.0.0.1:45008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-07T20:08:08.787355Z","caller":"traceutil/trace.go:171","msg":"trace[346868626] linearizableReadLoop","detail":"{readStateIndex:1238; appliedIndex:1237; }","duration":"446.139518ms","start":"2026-07-07T20:08:08.341187Z","end":"2026-07-07T20:08:08.787327Z","steps":["trace[346868626] 'read index received' (duration: 446.006105ms)","trace[346868626] 'applied index is now lower than readState.Index' (duration: 132.553µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:08:08.787416Z","caller":"traceutil/trace.go:171","msg":"trace[777704427] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"561.097233ms","start":"2026-07-07T20:08:08.226295Z","end":"2026-07-07T20:08:08.787392Z","steps":["trace[777704427] 'process raft request' (duration: 560.854228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:08:08.78755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.371245ms","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-07-07T20:08:08.787613Z","caller":"traceutil/trace.go:171","msg":"trace[1470582923] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1189; }","duration":"446.449056ms","start":"2026-07-07T20:08:08.341153Z","end":"2026-07-07T20:08:08.787602Z","steps":["trace[1470582923] 'agreement among raft nodes before linearized reading' (duration: 446.273881ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:08:08.787636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:08:08.341043Z","time spent":"446.58753ms","remote":"127.0.0.1:45008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-07T20:08:08.787713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:08:08.226238Z","time spent":"561.265099ms","remote":"127.0.0.1:45008","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-07-07T20:08:08.806173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.74137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-07T20:08:08.806209Z","caller":"traceutil/trace.go:171","msg":"trace[167384101] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"456.289863ms","start":"2026-07-07T20:08:08.34988Z","end":"2026-07-07T20:08:08.80617Z","steps":["trace[167384101] 'process raft request' (duration: 455.893403ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:08:08.806264Z","caller":"traceutil/trace.go:171","msg":"trace[1481613031] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1190; }","duration":"447.879293ms","start":"2026-07-07T20:08:08.358367Z","end":"2026-07-07T20:08:08.806246Z","steps":["trace[1481613031] 'agreement among raft nodes before linearized reading' (duration: 447.599995ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:08:08.806224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.867418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-07T20:08:08.806277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.413306ms","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-07-07T20:08:08.806219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.423911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-07T20:08:08.806312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:08:08.358354Z","time spent":"447.948475ms","remote":"127.0.0.1:45008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-07T20:08:08.80636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:08:08.349863Z","time spent":"456.410026ms","remote":"127.0.0.1:45008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T20:08:08.806345Z","caller":"traceutil/trace.go:171","msg":"trace[1260472334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1190; }","duration":"462.99474ms","start":"2026-07-07T20:08:08.343323Z","end":"2026-07-07T20:08:08.806317Z","steps":["trace[1260472334] 'agreement among raft nodes before linearized reading' (duration: 462.774965ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:08:08.806369Z","caller":"traceutil/trace.go:171","msg":"trace[923745756] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1190; }","duration":"463.624466ms","start":"2026-07-07T20:08:08.34273Z","end":"2026-07-07T20:08:08.806354Z","steps":["trace[923745756] 'agreement among raft nodes before linearized reading' (duration: 463.217156ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:08:08.806354Z","caller":"traceutil/trace.go:171","msg":"trace[1452280317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1190; }","duration":"448.547239ms","start":"2026-07-07T20:08:08.357789Z","end":"2026-07-07T20:08:08.806336Z","steps":["trace[1452280317] 'agreement among raft nodes before linearized reading' (duration: 448.262531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:08:08.806416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:08:08.343314Z","time spent":"463.091713ms","remote":"127.0.0.1:45008","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-07-07T20:08:08.806419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:08:08.342722Z","time spent":"463.683578ms","remote":"127.0.0.1:45008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-07T20:08:08.806457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:08:08.357766Z","time spent":"448.679783ms","remote":"127.0.0.1:45008","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":"info","ts":"2026-07-07T20:08:22.992691Z","caller":"traceutil/trace.go:171","msg":"trace[1963356782] linearizableReadLoop","detail":"{readStateIndex:1290; appliedIndex:1288; }","duration":"119.029987ms","start":"2026-07-07T20:08:22.873626Z","end":"2026-07-07T20:08:22.992656Z","steps":["trace[1963356782] 'read index received' (duration: 9.280092ms)","trace[1963356782] 'applied index is now lower than readState.Index' (duration: 109.748625ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:08:22.992811Z","caller":"traceutil/trace.go:171","msg":"trace[1191082754] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"121.532819ms","start":"2026-07-07T20:08:22.871267Z","end":"2026-07-07T20:08:22.9928Z","steps":["trace[1191082754] 'process raft request' (duration: 120.320119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:08:22.993066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.652438ms","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-07-07T20:08:22.993154Z","caller":"traceutil/trace.go:171","msg":"trace[1685376569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1238; }","duration":"118.7472ms","start":"2026-07-07T20:08:22.874387Z","end":"2026-07-07T20:08:22.993135Z","steps":["trace[1685376569] 'agreement among raft nodes before linearized reading' (duration: 118.559705ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:08:22.993228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.584981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-07T20:08:22.993267Z","caller":"traceutil/trace.go:171","msg":"trace[1324761289] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1238; }","duration":"119.656663ms","start":"2026-07-07T20:08:22.8736Z","end":"2026-07-07T20:08:22.993257Z","steps":["trace[1324761289] 'agreement among raft nodes before linearized reading' (duration: 119.52987ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:08:22.993397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.867003ms","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-07-07T20:08:22.993436Z","caller":"traceutil/trace.go:171","msg":"trace[1691750367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1238; }","duration":"118.930635ms","start":"2026-07-07T20:08:22.874497Z","end":"2026-07-07T20:08:22.993427Z","steps":["trace[1691750367] 'agreement among raft nodes before linearized reading' (duration: 118.833433ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:08:26.179816Z","caller":"traceutil/trace.go:171","msg":"trace[489344731] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1316; }","duration":"262.570407ms","start":"2026-07-07T20:08:25.917211Z","end":"2026-07-07T20:08:26.179782Z","steps":["trace[489344731] 'read index received' (duration: 177.361366ms)","trace[489344731] 'applied index is now lower than readState.Index' (duration: 85.207741ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:08:26.180033Z","caller":"traceutil/trace.go:171","msg":"trace[1698419128] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"280.507015ms","start":"2026-07-07T20:08:25.89951Z","end":"2026-07-07T20:08:26.180017Z","steps":["trace[1698419128] 'process raft request' (duration: 194.977676ms)","trace[1698419128] 'compare' (duration: 85.13579ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T20:08:26.180374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.637588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T20:08:26.180464Z","caller":"traceutil/trace.go:171","msg":"trace[909667053] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1264; }","duration":"120.814021ms","start":"2026-07-07T20:08:26.059626Z","end":"2026-07-07T20:08:26.18044Z","steps":["trace[909667053] 'agreement among raft nodes before linearized reading' (duration: 120.592816ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:08:26.180562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.319735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-hjst2\" ","response":"range_response_count:1 size:11057"} {"level":"info","ts":"2026-07-07T20:08:26.180604Z","caller":"traceutil/trace.go:171","msg":"trace[1069977740] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-hjst2; range_end:; response_count:1; response_revision:1264; }","duration":"263.412018ms","start":"2026-07-07T20:08:25.91718Z","end":"2026-07-07T20:08:26.180592Z","steps":["trace[1069977740] 'agreement among raft nodes before linearized reading' (duration: 263.211213ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:08:27.073319Z","caller":"traceutil/trace.go:171","msg":"trace[1166969774] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"174.557776ms","start":"2026-07-07T20:08:26.898737Z","end":"2026-07-07T20:08:27.073295Z","steps":["trace[1166969774] 'process raft request' (duration: 142.288388ms)","trace[1166969774] 'compare' (duration: 31.980171ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:08:27.210637Z","caller":"traceutil/trace.go:171","msg":"trace[646157501] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"132.168506ms","start":"2026-07-07T20:08:27.078436Z","end":"2026-07-07T20:08:27.210604Z","steps":["trace[646157501] 'process raft request' (duration: 97.736295ms)","trace[646157501] 'compare' (duration: 34.199086ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:08:27.210672Z","caller":"traceutil/trace.go:171","msg":"trace[1879090781] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"131.51632ms","start":"2026-07-07T20:08:27.079127Z","end":"2026-07-07T20:08:27.210643Z","steps":["trace[1879090781] 'process raft request' (duration: 131.447239ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:08:27.210765Z","caller":"traceutil/trace.go:171","msg":"trace[1686301202] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"132.227757ms","start":"2026-07-07T20:08:27.078513Z","end":"2026-07-07T20:08:27.210741Z","steps":["trace[1686301202] 'process raft request' (duration: 132.020702ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T20:08:39.286988Z","caller":"traceutil/trace.go:171","msg":"trace[2116439583] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"460.098528ms","start":"2026-07-07T20:08:38.826862Z","end":"2026-07-07T20:08:39.28696Z","steps":["trace[2116439583] 'process raft request' (duration: 459.958085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:08:39.287179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:08:38.826834Z","time spent":"460.252042ms","remote":"127.0.0.1:45008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T20:08:39.344129Z","caller":"traceutil/trace.go:171","msg":"trace[528312176] linearizableReadLoop","detail":"{readStateIndex:1413; appliedIndex:1412; }","duration":"216.400963ms","start":"2026-07-07T20:08:39.127703Z","end":"2026-07-07T20:08:39.344104Z","steps":["trace[528312176] 'read index received' (duration: 159.252813ms)","trace[528312176] 'applied index is now lower than readState.Index' (duration: 57.14689ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:08:39.344159Z","caller":"traceutil/trace.go:171","msg":"trace[471306493] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"453.885314ms","start":"2026-07-07T20:08:38.890251Z","end":"2026-07-07T20:08:39.344136Z","steps":["trace[471306493] 'process raft request' (duration: 426.737554ms)","trace[471306493] 'compare' (duration: 26.926163ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T20:08:39.344265Z","caller":"traceutil/trace.go:171","msg":"trace[1699443031] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"162.022663ms","start":"2026-07-07T20:08:39.182231Z","end":"2026-07-07T20:08:39.344253Z","steps":["trace[1699443031] 'process raft request' (duration: 161.822368ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:08:39.344309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T20:08:38.890205Z","time spent":"454.056498ms","remote":"127.0.0.1:45008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T20:08:39.344525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.812232ms","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-07-07T20:08:39.34456Z","caller":"traceutil/trace.go:171","msg":"trace[1504135566] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1359; }","duration":"216.909665ms","start":"2026-07-07T20:08:39.127639Z","end":"2026-07-07T20:08:39.344549Z","steps":["trace[1504135566] 'agreement among raft nodes before linearized reading' (duration: 216.751672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T20:08:39.344587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.554186ms","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-07-07T20:08:39.344632Z","caller":"traceutil/trace.go:171","msg":"trace[1899346958] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1359; }","duration":"176.643368ms","start":"2026-07-07T20:08:39.167976Z","end":"2026-07-07T20:08:39.34462Z","steps":["trace[1899346958] 'agreement among raft nodes before linearized reading' (duration: 176.513195ms)"],"step_count":1}