{"level":"warn","ts":"2026-06-30T17:53:40.668128Z","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-30T17:53:40.668198Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.235: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.235:2380","--initial-cluster=instance=https://199.204.45.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.235: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-30T17:53:40.668255Z","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-30T17:53:40.668265Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-06-30T17:53:40.66828Z","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-30T17:53:40.668844Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"]} {"level":"info","ts":"2026-06-30T17:53:40.668911Z","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.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.235: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-30T17:53:40.672474Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.364445ms"} {"level":"info","ts":"2026-06-30T17:53:40.677697Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-06-30T17:53:40.67777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-06-30T17:53:40.677796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-06-30T17:53:40.677812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aab6040abcf11 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-30T17:53:40.677827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-06-30T17:53:40.677862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-06-30T17:53:40.687386Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-30T17:53:40.689172Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-30T17:53:40.690233Z","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-30T17:53:40.69322Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aab6040abcf11","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-30T17:53:40.693513Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aab6040abcf11","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-30T17:53:40.693563Z","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-30T17:53:40.693682Z","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-30T17:53:40.69376Z","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-30T17:53:40.694095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-06-30T17:53:40.694755Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","added-peer-id":"aab6040abcf11","added-peer-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-06-30T17:53:40.697324Z","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-30T17:53:40.697528Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aab6040abcf11","initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-30T17:53:40.697561Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-30T17:53:40.697677Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-06-30T17:53:40.697694Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-06-30T17:53:41.578546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-06-30T17:53:41.578621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-30T17:53:41.578657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-06-30T17:53:41.578784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-06-30T17:53:41.578853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-06-30T17:53:41.578939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-06-30T17:53:41.578962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-06-30T17:53:41.580214Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aab6040abcf11","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.235:2379]}","request-path":"/0/members/aab6040abcf11/attributes","cluster-id":"2bae7d3d8857c059","publish-timeout":"7s"} {"level":"info","ts":"2026-06-30T17:53:41.580222Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-30T17:53:41.580219Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-30T17:53:41.580255Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-30T17:53:41.580629Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-30T17:53:41.580725Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-30T17:53:41.58119Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","cluster-version":"3.5"} {"level":"info","ts":"2026-06-30T17:53:41.58128Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-30T17:53:41.581302Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-30T17:53:41.583988Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-30T17:53:41.584014Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"info","ts":"2026-06-30T17:54:05.647176Z","caller":"traceutil/trace.go:171","msg":"trace[212066641] linearizableReadLoop","detail":"{readStateIndex:414; appliedIndex:413; }","duration":"246.784595ms","start":"2026-06-30T17:54:05.400357Z","end":"2026-06-30T17:54:05.647142Z","steps":["trace[212066641] 'read index received' (duration: 246.53444ms)","trace[212066641] 'applied index is now lower than readState.Index' (duration: 248.985µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:54:05.647291Z","caller":"traceutil/trace.go:171","msg":"trace[1438408446] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"325.39215ms","start":"2026-06-30T17:54:05.321866Z","end":"2026-06-30T17:54:05.647258Z","steps":["trace[1438408446] 'process raft request' (duration: 325.106674ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:54:05.647338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.958679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:54:05.647423Z","caller":"traceutil/trace.go:171","msg":"trace[1390630914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:396; }","duration":"247.089081ms","start":"2026-06-30T17:54:05.400315Z","end":"2026-06-30T17:54:05.647404Z","steps":["trace[1390630914] 'agreement among raft nodes before linearized reading' (duration: 246.963648ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:54:05.647874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:54:05.321849Z","time spent":"325.525082ms","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:54:23.865851Z","caller":"traceutil/trace.go:171","msg":"trace[730089018] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"107.602183ms","start":"2026-06-30T17:54:23.758225Z","end":"2026-06-30T17:54:23.865827Z","steps":["trace[730089018] 'process raft request' (duration: 107.395809ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:54:27.739484Z","caller":"traceutil/trace.go:171","msg":"trace[924214587] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"171.056506ms","start":"2026-06-30T17:54:27.568402Z","end":"2026-06-30T17:54:27.739458Z","steps":["trace[924214587] 'process raft request' (duration: 170.546046ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:54:28.202629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.983889ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-30T17:54:28.202733Z","caller":"traceutil/trace.go:171","msg":"trace[55173802] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"323.678787ms","start":"2026-06-30T17:54:27.879038Z","end":"2026-06-30T17:54:28.202717Z","steps":["trace[55173802] 'read index received' (duration: 172.353481ms)","trace[55173802] 'applied index is now lower than readState.Index' (duration: 151.324126ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:54:28.202951Z","caller":"traceutil/trace.go:171","msg":"trace[754525651] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"401.297192ms","start":"2026-06-30T17:54:27.801642Z","end":"2026-06-30T17:54:28.20294Z","steps":["trace[754525651] 'process raft request' (duration: 249.766833ms)","trace[754525651] 'compare' (duration: 150.839826ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:54:28.203009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:54:27.80162Z","time spent":"401.365203ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-30T17:54:28.203549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.459042ms","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-30T17:54:28.20373Z","caller":"traceutil/trace.go:171","msg":"trace[240038526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:603; }","duration":"324.735727ms","start":"2026-06-30T17:54:27.878971Z","end":"2026-06-30T17:54:28.203707Z","steps":["trace[240038526] 'agreement among raft nodes before linearized reading' (duration: 324.238707ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:54:28.203899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:54:27.878951Z","time spent":"324.933781ms","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-30T17:54:29.607659Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920881973035747869,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-30T17:54:30.011299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.675176ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:54:30.011448Z","caller":"traceutil/trace.go:171","msg":"trace[753252075] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:605; }","duration":"405.846629ms","start":"2026-06-30T17:54:29.605572Z","end":"2026-06-30T17:54:30.011419Z","steps":["trace[753252075] 'range keys from in-memory index tree' (duration: 405.651726ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:54:30.011588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.611151713s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:54:30.011691Z","caller":"traceutil/trace.go:171","msg":"trace[880017510] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"1.611302536s","start":"2026-06-30T17:54:28.400362Z","end":"2026-06-30T17:54:30.011665Z","steps":["trace[880017510] 'range keys from in-memory index tree' (duration: 1.611023171s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:54:30.01176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:54:28.400321Z","time spent":"1.611424038s","remote":"127.0.0.1:41252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-30T17:54:30.108112Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920881973035747869,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-30T17:54:30.168362Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.544182802s","expected-duration":"1s"} {"level":"info","ts":"2026-06-30T17:54:30.169416Z","caller":"traceutil/trace.go:171","msg":"trace[1939488806] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"1.546984846s","start":"2026-06-30T17:54:28.6224Z","end":"2026-06-30T17:54:30.169385Z","steps":["trace[1939488806] 'process raft request' (duration: 1.546422475s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:54:30.169573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:54:28.622372Z","time spent":"1.547116499s","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T17:54:30.374376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.150785ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:26"} {"level":"info","ts":"2026-06-30T17:54:30.374615Z","caller":"traceutil/trace.go:171","msg":"trace[222066918] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:631; }","duration":"1.267619793s","start":"2026-06-30T17:54:29.106958Z","end":"2026-06-30T17:54:30.374578Z","steps":["trace[222066918] 'read index received' (duration: 1.061601014s)","trace[222066918] 'applied index is now lower than readState.Index' (duration: 206.015199ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:54:30.37472Z","caller":"traceutil/trace.go:171","msg":"trace[1696125565] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"137.705122ms","start":"2026-06-30T17:54:30.236995Z","end":"2026-06-30T17:54:30.374701Z","steps":["trace[1696125565] 'process raft request' (duration: 137.498529ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:54:30.374978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.2029998s","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-30T17:54:30.375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.473689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:54:30.375045Z","caller":"traceutil/trace.go:171","msg":"trace[329552794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:607; }","duration":"1.203142833s","start":"2026-06-30T17:54:29.171884Z","end":"2026-06-30T17:54:30.375027Z","steps":["trace[329552794] 'agreement among raft nodes before linearized reading' (duration: 1.202887107s)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:54:30.375052Z","caller":"traceutil/trace.go:171","msg":"trace[416123999] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"356.52947ms","start":"2026-06-30T17:54:30.018507Z","end":"2026-06-30T17:54:30.375037Z","steps":["trace[416123999] 'agreement among raft nodes before linearized reading' (duration: 356.429648ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:54:30.375096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:54:29.171857Z","time spent":"1.203226824s","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-30T17:54:30.375089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.173682494s","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-06-30T17:54:30.375096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:54:30.018465Z","time spent":"356.619402ms","remote":"127.0.0.1:41268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-30T17:54:30.375162Z","caller":"traceutil/trace.go:171","msg":"trace[108043508] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:607; }","duration":"1.173792067s","start":"2026-06-30T17:54:29.201353Z","end":"2026-06-30T17:54:30.375145Z","steps":["trace[108043508] 'agreement among raft nodes before linearized reading' (duration: 1.173587903s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:54:30.375202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:54:29.201333Z","time spent":"1.173861048s","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-30T17:54:30.375012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.268040932s","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-06-30T17:54:30.375515Z","caller":"traceutil/trace.go:171","msg":"trace[1957748450] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:607; }","duration":"1.268593532s","start":"2026-06-30T17:54:29.106903Z","end":"2026-06-30T17:54:30.375497Z","steps":["trace[1957748450] 'agreement among raft nodes before linearized reading' (duration: 1.267899238s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:54:30.375737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:54:29.106884Z","time spent":"1.268732954s","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-30T17:54:30.37609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.890232ms","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-30T17:54:30.376577Z","caller":"traceutil/trace.go:171","msg":"trace[465659694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:607; }","duration":"144.407323ms","start":"2026-06-30T17:54:30.232148Z","end":"2026-06-30T17:54:30.376556Z","steps":["trace[465659694] 'agreement among raft nodes before linearized reading' (duration: 142.678889ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:54:31.503532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.564724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:54:31.503591Z","caller":"traceutil/trace.go:171","msg":"trace[79753059] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"106.674416ms","start":"2026-06-30T17:54:31.396904Z","end":"2026-06-30T17:54:31.503579Z","steps":["trace[79753059] 'range keys from in-memory index tree' (duration: 106.497963ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:54:32.438686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.564902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:54:32.438763Z","caller":"traceutil/trace.go:171","msg":"trace[1037589951] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:649; }","duration":"107.688255ms","start":"2026-06-30T17:54:32.331056Z","end":"2026-06-30T17:54:32.438744Z","steps":["trace[1037589951] 'range keys from in-memory index tree' (duration: 107.446469ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:54:45.901559Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920881973035748182,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-30T17:54:45.987358Z","caller":"traceutil/trace.go:171","msg":"trace[1288281028] linearizableReadLoop","detail":"{readStateIndex:799; appliedIndex:798; }","duration":"586.726275ms","start":"2026-06-30T17:54:45.400606Z","end":"2026-06-30T17:54:45.987332Z","steps":["trace[1288281028] 'read index received' (duration: 586.48079ms)","trace[1288281028] 'applied index is now lower than readState.Index' (duration: 244.405µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:54:45.987477Z","caller":"traceutil/trace.go:171","msg":"trace[678979768] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"749.99065ms","start":"2026-06-30T17:54:45.237451Z","end":"2026-06-30T17:54:45.987441Z","steps":["trace[678979768] 'process raft request' (duration: 749.677324ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:54:45.987554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.921179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:54:45.987662Z","caller":"traceutil/trace.go:171","msg":"trace[1750143561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:768; }","duration":"587.087462ms","start":"2026-06-30T17:54:45.400562Z","end":"2026-06-30T17:54:45.987649Z","steps":["trace[1750143561] 'agreement among raft nodes before linearized reading' (duration: 586.926379ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:54:45.98776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:54:45.400541Z","time spent":"587.206164ms","remote":"127.0.0.1:41268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-30T17:54:45.987672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:54:45.237426Z","time spent":"750.143382ms","remote":"127.0.0.1:41476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T17:54:45.98798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.311444ms","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-06-30T17:54:45.988054Z","caller":"traceutil/trace.go:171","msg":"trace[1612576010] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:768; }","duration":"193.418677ms","start":"2026-06-30T17:54:45.794619Z","end":"2026-06-30T17:54:45.988038Z","steps":["trace[1612576010] 'agreement among raft nodes before linearized reading' (duration: 193.08889ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:00.313749Z","caller":"traceutil/trace.go:171","msg":"trace[128149630] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"629.666772ms","start":"2026-06-30T17:54:59.684055Z","end":"2026-06-30T17:55:00.313722Z","steps":["trace[128149630] 'process raft request' (duration: 629.514239ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:00.313905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:54:59.684011Z","time spent":"629.820615ms","remote":"127.0.0.1:41476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5469,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:55:00.366392Z","caller":"traceutil/trace.go:171","msg":"trace[1672076122] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:931; }","duration":"279.936933ms","start":"2026-06-30T17:55:00.086203Z","end":"2026-06-30T17:55:00.366139Z","steps":["trace[1672076122] 'read index received' (duration: 227.338427ms)","trace[1672076122] 'applied index is now lower than readState.Index' (duration: 52.596906ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:55:00.36646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.244908ms","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-30T17:55:00.366517Z","caller":"traceutil/trace.go:171","msg":"trace[1645675494] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:898; }","duration":"280.341951ms","start":"2026-06-30T17:55:00.086161Z","end":"2026-06-30T17:55:00.366503Z","steps":["trace[1645675494] 'agreement among raft nodes before linearized reading' (duration: 280.169317ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:16.999519Z","caller":"traceutil/trace.go:171","msg":"trace[1821404071] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"176.608029ms","start":"2026-06-30T17:55:16.822891Z","end":"2026-06-30T17:55:16.999499Z","steps":["trace[1821404071] 'process raft request' (duration: 96.963812ms)","trace[1821404071] 'compare' (duration: 79.523894ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:55:16.999779Z","caller":"traceutil/trace.go:171","msg":"trace[1081486893] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"176.097339ms","start":"2026-06-30T17:55:16.823626Z","end":"2026-06-30T17:55:16.999723Z","steps":["trace[1081486893] 'process raft request' (duration: 175.842094ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:21.331458Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920881973035748714,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-30T17:55:21.784988Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.248889214s","expected-duration":"1s"} {"level":"info","ts":"2026-06-30T17:55:21.785362Z","caller":"traceutil/trace.go:171","msg":"trace[1459789337] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"1.249340254s","start":"2026-06-30T17:55:20.535999Z","end":"2026-06-30T17:55:21.785339Z","steps":["trace[1459789337] 'process raft request' (duration: 1.249156391s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:21.7855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:55:20.535979Z","time spent":"1.249450576s","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:55:21.968299Z","caller":"traceutil/trace.go:171","msg":"trace[1982496433] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1017; }","duration":"1.137826571s","start":"2026-06-30T17:55:20.830453Z","end":"2026-06-30T17:55:21.96828Z","steps":["trace[1982496433] 'read index received' (duration: 954.740817ms)","trace[1982496433] 'applied index is now lower than readState.Index' (duration: 183.084824ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:55:21.968356Z","caller":"traceutil/trace.go:171","msg":"trace[1866494552] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"1.286278775s","start":"2026-06-30T17:55:20.682042Z","end":"2026-06-30T17:55:21.96832Z","steps":["trace[1866494552] 'process raft request' (duration: 1.285687634s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:21.968461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.137990414s","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-06-30T17:55:21.96849Z","caller":"traceutil/trace.go:171","msg":"trace[1378152546] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:979; }","duration":"1.138067796s","start":"2026-06-30T17:55:20.830414Z","end":"2026-06-30T17:55:21.968482Z","steps":["trace[1378152546] 'agreement among raft nodes before linearized reading' (duration: 1.137956763s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:21.968514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:55:20.830397Z","time spent":"1.138110807s","remote":"127.0.0.1:41552","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-30T17:55:21.96852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.029912779s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-30T17:55:21.968521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:55:20.682019Z","time spent":"1.286403208s","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:55:21.968572Z","caller":"traceutil/trace.go:171","msg":"trace[993178807] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:979; }","duration":"1.03000578s","start":"2026-06-30T17:55:20.938552Z","end":"2026-06-30T17:55:21.968558Z","steps":["trace[993178807] 'agreement among raft nodes before linearized reading' (duration: 1.029872166s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:21.968607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:55:20.938529Z","time spent":"1.030069511s","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-30T17:55:21.968632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.388599ms","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-30T17:55:21.968696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.597424ms","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-06-30T17:55:21.968701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.36076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:55:21.968716Z","caller":"traceutil/trace.go:171","msg":"trace[1785726090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:979; }","duration":"956.643425ms","start":"2026-06-30T17:55:21.012067Z","end":"2026-06-30T17:55:21.968711Z","steps":["trace[1785726090] 'agreement among raft nodes before linearized reading' (duration: 956.587794ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:21.968739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:55:21.012053Z","time spent":"956.681886ms","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-30T17:55:21.968696Z","caller":"traceutil/trace.go:171","msg":"trace[654844037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:979; }","duration":"956.463371ms","start":"2026-06-30T17:55:21.012217Z","end":"2026-06-30T17:55:21.968681Z","steps":["trace[654844037] 'agreement among raft nodes before linearized reading' (duration: 956.301668ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:21.968752Z","caller":"traceutil/trace.go:171","msg":"trace[910231393] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:979; }","duration":"568.436482ms","start":"2026-06-30T17:55:21.400297Z","end":"2026-06-30T17:55:21.968734Z","steps":["trace[910231393] 'agreement among raft nodes before linearized reading' (duration: 568.378041ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:21.968788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:55:21.012213Z","time spent":"956.566393ms","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-30T17:55:21.968794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:55:21.400281Z","time spent":"568.504373ms","remote":"127.0.0.1:41268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-30T17:55:21.968633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.538448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-30T17:55:21.968861Z","caller":"traceutil/trace.go:171","msg":"trace[1968655941] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:979; }","duration":"834.817602ms","start":"2026-06-30T17:55:21.134035Z","end":"2026-06-30T17:55:21.968853Z","steps":["trace[1968655941] 'agreement among raft nodes before linearized reading' (duration: 834.450955ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:21.96889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:55:21.134021Z","time spent":"834.857843ms","remote":"127.0.0.1:41354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":92,"response size":28,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-06-30T17:55:23.896873Z","caller":"traceutil/trace.go:171","msg":"trace[369516830] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"103.63546ms","start":"2026-06-30T17:55:23.793219Z","end":"2026-06-30T17:55:23.896855Z","steps":["trace[369516830] 'process raft request' (duration: 103.535398ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:24.876517Z","caller":"traceutil/trace.go:171","msg":"trace[1280024004] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"200.449098ms","start":"2026-06-30T17:55:24.676045Z","end":"2026-06-30T17:55:24.876494Z","steps":["trace[1280024004] 'process raft request' (duration: 200.319616ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:30.157846Z","caller":"traceutil/trace.go:171","msg":"trace[1885132299] linearizableReadLoop","detail":"{readStateIndex:1048; appliedIndex:1047; }","duration":"149.981574ms","start":"2026-06-30T17:55:30.007826Z","end":"2026-06-30T17:55:30.157807Z","steps":["trace[1885132299] 'read index received' (duration: 78.144738ms)","trace[1885132299] 'applied index is now lower than readState.Index' (duration: 71.835526ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:55:30.157914Z","caller":"traceutil/trace.go:171","msg":"trace[1957459128] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"156.95652ms","start":"2026-06-30T17:55:30.000912Z","end":"2026-06-30T17:55:30.157869Z","steps":["trace[1957459128] 'process raft request' (duration: 85.087932ms)","trace[1957459128] 'compare' (duration: 71.682654ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:55:30.158065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.226901ms","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-06-30T17:55:30.158147Z","caller":"traceutil/trace.go:171","msg":"trace[1307353346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1006; }","duration":"150.354202ms","start":"2026-06-30T17:55:30.007772Z","end":"2026-06-30T17:55:30.158126Z","steps":["trace[1307353346] 'agreement among raft nodes before linearized reading' (duration: 150.166619ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:30.158435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.056385ms","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-30T17:55:30.15852Z","caller":"traceutil/trace.go:171","msg":"trace[1225459185] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1006; }","duration":"137.180158ms","start":"2026-06-30T17:55:30.021316Z","end":"2026-06-30T17:55:30.158496Z","steps":["trace[1225459185] 'agreement among raft nodes before linearized reading' (duration: 136.954814ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:30.158457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.793051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-30T17:55:30.15861Z","caller":"traceutil/trace.go:171","msg":"trace[1330806567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1006; }","duration":"136.984655ms","start":"2026-06-30T17:55:30.021603Z","end":"2026-06-30T17:55:30.158588Z","steps":["trace[1330806567] 'agreement among raft nodes before linearized reading' (duration: 136.669999ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:30.158616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.173328ms","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-06-30T17:55:30.158663Z","caller":"traceutil/trace.go:171","msg":"trace[205069160] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1006; }","duration":"137.26097ms","start":"2026-06-30T17:55:30.021389Z","end":"2026-06-30T17:55:30.15865Z","steps":["trace[205069160] 'agreement among raft nodes before linearized reading' (duration: 137.106047ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:30.386828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.32613ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-30T17:55:30.387421Z","caller":"traceutil/trace.go:171","msg":"trace[314961263] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"224.762418ms","start":"2026-06-30T17:55:30.162637Z","end":"2026-06-30T17:55:30.387399Z","steps":["trace[314961263] 'process raft request' (duration: 224.414721ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:30.387517Z","caller":"traceutil/trace.go:171","msg":"trace[1175195632] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"224.792909ms","start":"2026-06-30T17:55:30.1627Z","end":"2026-06-30T17:55:30.387493Z","steps":["trace[1175195632] 'process raft request' (duration: 224.387121ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:30.38755Z","caller":"traceutil/trace.go:171","msg":"trace[711521184] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"224.944912ms","start":"2026-06-30T17:55:30.162576Z","end":"2026-06-30T17:55:30.387521Z","steps":["trace[711521184] 'process raft request' (duration: 113.829546ms)","trace[711521184] 'compare' (duration: 110.208728ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:55:30.387718Z","caller":"traceutil/trace.go:171","msg":"trace[1237091154] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"225.058914ms","start":"2026-06-30T17:55:30.162637Z","end":"2026-06-30T17:55:30.387696Z","steps":["trace[1237091154] 'process raft request' (duration: 224.35173ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:45.406956Z","caller":"traceutil/trace.go:171","msg":"trace[542076910] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"272.438499ms","start":"2026-06-30T17:55:45.134494Z","end":"2026-06-30T17:55:45.406933Z","steps":["trace[542076910] 'process raft request' (duration: 272.057121ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:47.118377Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920881973035748916,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-30T17:55:47.334872Z","caller":"traceutil/trace.go:171","msg":"trace[1731063234] linearizableReadLoop","detail":"{readStateIndex:1105; appliedIndex:1104; }","duration":"716.707753ms","start":"2026-06-30T17:55:46.618141Z","end":"2026-06-30T17:55:47.334849Z","steps":["trace[1731063234] 'read index received' (duration: 716.57831ms)","trace[1731063234] 'applied index is now lower than readState.Index' (duration: 128.163µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:55:47.334934Z","caller":"traceutil/trace.go:171","msg":"trace[1311033958] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"834.040359ms","start":"2026-06-30T17:55:46.500851Z","end":"2026-06-30T17:55:47.334892Z","steps":["trace[1311033958] 'process raft request' (duration: 833.824994ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:47.335098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.261432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-30T17:55:47.335119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.967789ms","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-06-30T17:55:47.335152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.451728ms","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-30T17:55:47.335182Z","caller":"traceutil/trace.go:171","msg":"trace[1651771285] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1060; }","duration":"717.0759ms","start":"2026-06-30T17:55:46.618091Z","end":"2026-06-30T17:55:47.335167Z","steps":["trace[1651771285] 'agreement among raft nodes before linearized reading' (duration: 716.902707ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:47.335209Z","caller":"traceutil/trace.go:171","msg":"trace[162541815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1060; }","duration":"716.54495ms","start":"2026-06-30T17:55:46.618653Z","end":"2026-06-30T17:55:47.335198Z","steps":["trace[162541815] 'agreement among raft nodes before linearized reading' (duration: 716.357157ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:47.335223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:55:46.618073Z","time spent":"717.140602ms","remote":"127.0.0.1:41552","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-30T17:55:47.33524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.084056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-30T17:55:47.335255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:55:46.618645Z","time spent":"716.595951ms","remote":"127.0.0.1:41552","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-06-30T17:55:47.335277Z","caller":"traceutil/trace.go:171","msg":"trace[492818767] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1060; }","duration":"638.157418ms","start":"2026-06-30T17:55:46.697111Z","end":"2026-06-30T17:55:47.335269Z","steps":["trace[492818767] 'agreement among raft nodes before linearized reading' (duration: 638.098007ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:47.335173Z","caller":"traceutil/trace.go:171","msg":"trace[2101325238] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1060; }","duration":"506.376674ms","start":"2026-06-30T17:55:46.828777Z","end":"2026-06-30T17:55:47.335153Z","steps":["trace[2101325238] 'agreement among raft nodes before linearized reading' (duration: 506.268852ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:47.335313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:55:46.69709Z","time spent":"638.215108ms","remote":"127.0.0.1:41560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":26,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-30T17:55:47.335372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:55:46.828758Z","time spent":"506.579108ms","remote":"127.0.0.1:41268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-30T17:55:47.335383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:55:46.500835Z","time spent":"834.281233ms","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T17:55:47.335717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.100602ms","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-30T17:55:47.335805Z","caller":"traceutil/trace.go:171","msg":"trace[221291940] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1060; }","duration":"701.199564ms","start":"2026-06-30T17:55:46.634585Z","end":"2026-06-30T17:55:47.335785Z","steps":["trace[221291940] 'agreement among raft nodes before linearized reading' (duration: 700.678715ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:47.335851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:55:46.634555Z","time spent":"701.286226ms","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-30T17:55:47.958624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.079727ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-30T17:55:47.958859Z","caller":"traceutil/trace.go:171","msg":"trace[1177149205] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"618.723002ms","start":"2026-06-30T17:55:47.340116Z","end":"2026-06-30T17:55:47.958839Z","steps":["trace[1177149205] 'process raft request' (duration: 69.351439ms)","trace[1177149205] 'compare' (duration: 548.978105ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:55:47.958928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:55:47.340098Z","time spent":"618.797514ms","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:55:47.959065Z","caller":"traceutil/trace.go:171","msg":"trace[1239387993] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"618.833584ms","start":"2026-06-30T17:55:47.3402Z","end":"2026-06-30T17:55:47.959034Z","steps":["trace[1239387993] 'process raft request' (duration: 618.59301ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:47.959103Z","caller":"traceutil/trace.go:171","msg":"trace[921409034] linearizableReadLoop","detail":"{readStateIndex:1108; appliedIndex:1105; }","duration":"561.18758ms","start":"2026-06-30T17:55:47.397893Z","end":"2026-06-30T17:55:47.959081Z","steps":["trace[921409034] 'read index received' (duration: 11.584153ms)","trace[921409034] 'applied index is now lower than readState.Index' (duration: 549.602577ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:55:47.959209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:55:47.340174Z","time spent":"618.961066ms","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T17:55:47.959242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.337644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:55:47.959275Z","caller":"traceutil/trace.go:171","msg":"trace[566412810] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1063; }","duration":"561.416565ms","start":"2026-06-30T17:55:47.397848Z","end":"2026-06-30T17:55:47.959265Z","steps":["trace[566412810] 'agreement among raft nodes before linearized reading' (duration: 561.323714ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:47.959306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:55:47.397831Z","time spent":"561.467746ms","remote":"127.0.0.1:41252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-30T17:55:47.95933Z","caller":"traceutil/trace.go:171","msg":"trace[1541893241] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"618.141181ms","start":"2026-06-30T17:55:47.341161Z","end":"2026-06-30T17:55:47.959303Z","steps":["trace[1541893241] 'process raft request' (duration: 617.824104ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:47.959482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:55:47.341153Z","time spent":"618.258653ms","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T17:55:48.448322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.848676ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-30T17:55:48.448436Z","caller":"traceutil/trace.go:171","msg":"trace[375321862] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"185.007501ms","start":"2026-06-30T17:55:48.263409Z","end":"2026-06-30T17:55:48.448416Z","steps":["trace[375321862] 'process raft request' (duration: 54.953431ms)","trace[375321862] 'compare' (duration: 129.702814ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:55:48.591086Z","caller":"traceutil/trace.go:171","msg":"trace[1601461301] linearizableReadLoop","detail":"{readStateIndex:1111; appliedIndex:1110; }","duration":"192.055077ms","start":"2026-06-30T17:55:48.399006Z","end":"2026-06-30T17:55:48.591061Z","steps":["trace[1601461301] 'read index received' (duration: 155.640384ms)","trace[1601461301] 'applied index is now lower than readState.Index' (duration: 36.413753ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:55:48.591139Z","caller":"traceutil/trace.go:171","msg":"trace[1239074006] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"313.42726ms","start":"2026-06-30T17:55:48.277681Z","end":"2026-06-30T17:55:48.591108Z","steps":["trace[1239074006] 'process raft request' (duration: 277.024097ms)","trace[1239074006] 'compare' (duration: 36.188809ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:55:48.591189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.164529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:55:48.591222Z","caller":"traceutil/trace.go:171","msg":"trace[933848208] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1066; }","duration":"192.252271ms","start":"2026-06-30T17:55:48.398961Z","end":"2026-06-30T17:55:48.591213Z","steps":["trace[933848208] 'agreement among raft nodes before linearized reading' (duration: 192.182499ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:48.591472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:55:48.277666Z","time spent":"313.531341ms","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:55:49.787697Z","caller":"traceutil/trace.go:171","msg":"trace[447379551] linearizableReadLoop","detail":"{readStateIndex:1112; appliedIndex:1111; }","duration":"387.276584ms","start":"2026-06-30T17:55:49.400396Z","end":"2026-06-30T17:55:49.787673Z","steps":["trace[447379551] 'read index received' (duration: 387.120591ms)","trace[447379551] 'applied index is now lower than readState.Index' (duration: 154.753µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:55:49.787741Z","caller":"traceutil/trace.go:171","msg":"trace[1965389858] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"441.96103ms","start":"2026-06-30T17:55:49.345743Z","end":"2026-06-30T17:55:49.787704Z","steps":["trace[1965389858] 'process raft request' (duration: 441.784267ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:49.787892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.387411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-30T17:55:49.787962Z","caller":"traceutil/trace.go:171","msg":"trace[1792310577] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1067; }","duration":"382.500704ms","start":"2026-06-30T17:55:49.405451Z","end":"2026-06-30T17:55:49.787952Z","steps":["trace[1792310577] 'agreement among raft nodes before linearized reading' (duration: 382.357311ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:49.787918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:55:49.345724Z","time spent":"442.094933ms","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T17:55:49.787894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.473799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-30T17:55:49.78806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:55:49.405441Z","time spent":"382.555604ms","remote":"127.0.0.1:41698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":28,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-06-30T17:55:49.78811Z","caller":"traceutil/trace.go:171","msg":"trace[846320469] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1067; }","duration":"387.711343ms","start":"2026-06-30T17:55:49.400353Z","end":"2026-06-30T17:55:49.788064Z","steps":["trace[846320469] 'agreement among raft nodes before linearized reading' (duration: 387.437348ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:49.788385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:55:49.400325Z","time spent":"388.045559ms","remote":"127.0.0.1:41268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-30T17:55:50.212508Z","caller":"traceutil/trace.go:171","msg":"trace[1047784104] linearizableReadLoop","detail":"{readStateIndex:1113; appliedIndex:1112; }","duration":"249.649519ms","start":"2026-06-30T17:55:49.96283Z","end":"2026-06-30T17:55:50.212479Z","steps":["trace[1047784104] 'read index received' (duration: 173.821985ms)","trace[1047784104] 'applied index is now lower than readState.Index' (duration: 75.825893ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:55:50.212778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.913773ms","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-06-30T17:55:50.212818Z","caller":"traceutil/trace.go:171","msg":"trace[237243200] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1067; }","duration":"250.026076ms","start":"2026-06-30T17:55:49.962782Z","end":"2026-06-30T17:55:50.212809Z","steps":["trace[237243200] 'agreement among raft nodes before linearized reading' (duration: 249.847382ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:50.212823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.630638ms","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-30T17:55:50.212908Z","caller":"traceutil/trace.go:171","msg":"trace[1152221756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1067; }","duration":"249.75416ms","start":"2026-06-30T17:55:49.963135Z","end":"2026-06-30T17:55:50.212889Z","steps":["trace[1152221756] 'agreement among raft nodes before linearized reading' (duration: 249.507226ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:50.213075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.387423ms","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-30T17:55:50.21311Z","caller":"traceutil/trace.go:171","msg":"trace[1827911083] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1067; }","duration":"249.437614ms","start":"2026-06-30T17:55:49.963664Z","end":"2026-06-30T17:55:50.213102Z","steps":["trace[1827911083] 'agreement among raft nodes before linearized reading' (duration: 249.350142ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:50.789713Z","caller":"traceutil/trace.go:171","msg":"trace[2145313130] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"336.415383ms","start":"2026-06-30T17:55:50.45321Z","end":"2026-06-30T17:55:50.789625Z","steps":["trace[2145313130] 'process raft request' (duration: 336.26661ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:50.78986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:55:50.453183Z","time spent":"336.603227ms","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:56:06.956719Z","caller":"traceutil/trace.go:171","msg":"trace[1682007360] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"117.070634ms","start":"2026-06-30T17:56:06.839613Z","end":"2026-06-30T17:56:06.956684Z","steps":["trace[1682007360] 'process raft request' (duration: 116.936181ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:08.85922Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920881973035749197,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-30T17:56:09.359703Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920881973035749197,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-30T17:56:09.550691Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.218194815s","expected-duration":"1s"} {"level":"info","ts":"2026-06-30T17:56:09.551055Z","caller":"traceutil/trace.go:171","msg":"trace[131838109] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"1.218658094s","start":"2026-06-30T17:56:08.332371Z","end":"2026-06-30T17:56:09.551029Z","steps":["trace[131838109] 'process raft request' (duration: 1.218505791s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:09.551217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:08.332338Z","time spent":"1.218797647s","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:56:09.551808Z","caller":"traceutil/trace.go:171","msg":"trace[2017939272] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"590.428026ms","start":"2026-06-30T17:56:08.961356Z","end":"2026-06-30T17:56:09.551784Z","steps":["trace[2017939272] 'process raft request' (duration: 590.299273ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:09.551957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:08.961329Z","time spent":"590.531717ms","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:56:09.551958Z","caller":"traceutil/trace.go:171","msg":"trace[243150379] linearizableReadLoop","detail":"{readStateIndex:1236; appliedIndex:1234; }","duration":"1.193191741s","start":"2026-06-30T17:56:08.358718Z","end":"2026-06-30T17:56:09.551909Z","steps":["trace[243150379] 'read index received' (duration: 1.192311265s)","trace[243150379] 'applied index is now lower than readState.Index' (duration: 879.096µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:56:09.552082Z","caller":"traceutil/trace.go:171","msg":"trace[1328691012] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"378.778374ms","start":"2026-06-30T17:56:09.173289Z","end":"2026-06-30T17:56:09.552068Z","steps":["trace[1328691012] 'process raft request' (duration: 378.459377ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:09.552159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.937329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-30T17:56:09.552182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:09.173272Z","time spent":"378.837095ms","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:56:09.552194Z","caller":"traceutil/trace.go:171","msg":"trace[254482947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1185; }","duration":"152.016689ms","start":"2026-06-30T17:56:09.400166Z","end":"2026-06-30T17:56:09.552183Z","steps":["trace[254482947] 'agreement among raft nodes before linearized reading' (duration: 151.957768ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:09.552187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.187507872s","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-06-30T17:56:09.552242Z","caller":"traceutil/trace.go:171","msg":"trace[1324646257] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1185; }","duration":"1.187619574s","start":"2026-06-30T17:56:08.364609Z","end":"2026-06-30T17:56:09.552228Z","steps":["trace[1324646257] 'agreement among raft nodes before linearized reading' (duration: 1.187479932s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:09.552277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:08.364589Z","time spent":"1.187679915s","remote":"127.0.0.1:41552","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-30T17:56:09.552405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.084147103s","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-06-30T17:56:09.552459Z","caller":"traceutil/trace.go:171","msg":"trace[464499888] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1185; }","duration":"1.084233105s","start":"2026-06-30T17:56:08.468211Z","end":"2026-06-30T17:56:09.552444Z","steps":["trace[464499888] 'agreement among raft nodes before linearized reading' (duration: 1.084088012s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:09.552477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.193762003s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-30T17:56:09.552488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:08.468187Z","time spent":"1.084293576s","remote":"127.0.0.1:41552","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":"info","ts":"2026-06-30T17:56:09.552513Z","caller":"traceutil/trace.go:171","msg":"trace[1208985003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1185; }","duration":"1.193822533s","start":"2026-06-30T17:56:08.358682Z","end":"2026-06-30T17:56:09.552505Z","steps":["trace[1208985003] 'agreement among raft nodes before linearized reading' (duration: 1.193689381s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:09.552614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:08.35867Z","time spent":"1.193933605s","remote":"127.0.0.1:41552","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-06-30T17:56:26.309247Z","caller":"traceutil/trace.go:171","msg":"trace[1053836331] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"136.02221ms","start":"2026-06-30T17:56:26.17319Z","end":"2026-06-30T17:56:26.309212Z","steps":["trace[1053836331] 'process raft request' (duration: 110.805162ms)","trace[1053836331] 'compare' (duration: 25.060125ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:56:26.957717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.64485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:56:26.957783Z","caller":"traceutil/trace.go:171","msg":"trace[1163649925] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1262; }","duration":"484.785814ms","start":"2026-06-30T17:56:26.472981Z","end":"2026-06-30T17:56:26.957767Z","steps":["trace[1163649925] 'count revisions from in-memory index tree' (duration: 484.520278ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:26.957814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:26.472957Z","time spent":"484.847755ms","remote":"127.0.0.1:43466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-06-30T17:56:27.834097Z","caller":"traceutil/trace.go:171","msg":"trace[1520308264] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"102.077174ms","start":"2026-06-30T17:56:27.732Z","end":"2026-06-30T17:56:27.834077Z","steps":["trace[1520308264] 'process raft request' (duration: 77.123201ms)","trace[1520308264] 'compare' (duration: 24.81142ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:56:27.834254Z","caller":"traceutil/trace.go:171","msg":"trace[1284882630] linearizableReadLoop","detail":"{readStateIndex:1321; appliedIndex:1318; }","duration":"101.250168ms","start":"2026-06-30T17:56:27.732976Z","end":"2026-06-30T17:56:27.834226Z","steps":["trace[1284882630] 'read index received' (duration: 23.842762ms)","trace[1284882630] 'applied index is now lower than readState.Index' (duration: 77.405696ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:56:27.834312Z","caller":"traceutil/trace.go:171","msg":"trace[662703827] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"101.940752ms","start":"2026-06-30T17:56:27.732343Z","end":"2026-06-30T17:56:27.834284Z","steps":["trace[662703827] 'process raft request' (duration: 101.698027ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:27.834426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.436291ms","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-06-30T17:56:27.834459Z","caller":"traceutil/trace.go:171","msg":"trace[608836829] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1266; }","duration":"101.503862ms","start":"2026-06-30T17:56:27.732947Z","end":"2026-06-30T17:56:27.834451Z","steps":["trace[608836829] 'agreement among raft nodes before linearized reading' (duration: 101.36504ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:28.902133Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920881973035749391,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-30T17:56:29.103781Z","caller":"traceutil/trace.go:171","msg":"trace[1507800348] linearizableReadLoop","detail":"{readStateIndex:1323; appliedIndex:1322; }","duration":"702.061555ms","start":"2026-06-30T17:56:28.401698Z","end":"2026-06-30T17:56:29.103759Z","steps":["trace[1507800348] 'read index received' (duration: 701.846551ms)","trace[1507800348] 'applied index is now lower than readState.Index' (duration: 214.044µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:56:29.103925Z","caller":"traceutil/trace.go:171","msg":"trace[1447022853] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"943.30483ms","start":"2026-06-30T17:56:28.160575Z","end":"2026-06-30T17:56:29.10388Z","steps":["trace[1447022853] 'process raft request' (duration: 943.029835ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:29.103996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.277329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:56:29.104037Z","caller":"traceutil/trace.go:171","msg":"trace[1750972186] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1268; }","duration":"702.35769ms","start":"2026-06-30T17:56:28.401667Z","end":"2026-06-30T17:56:29.104025Z","steps":["trace[1750972186] 'agreement among raft nodes before linearized reading' (duration: 702.226348ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:29.104079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:28.401653Z","time spent":"702.418312ms","remote":"127.0.0.1:41268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-30T17:56:29.104121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:28.160566Z","time spent":"943.456742ms","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T17:56:29.104177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.580835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:56:29.104249Z","caller":"traceutil/trace.go:171","msg":"trace[119024678] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1268; }","duration":"686.678297ms","start":"2026-06-30T17:56:28.41755Z","end":"2026-06-30T17:56:29.104228Z","steps":["trace[119024678] 'agreement among raft nodes before linearized reading' (duration: 686.550275ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:29.104292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:28.417514Z","time spent":"686.765339ms","remote":"127.0.0.1:43488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-06-30T17:56:29.703711Z","caller":"traceutil/trace.go:171","msg":"trace[126891697] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1323; }","duration":"144.364741ms","start":"2026-06-30T17:56:29.559321Z","end":"2026-06-30T17:56:29.703685Z","steps":["trace[126891697] 'read index received' (duration: 144.192287ms)","trace[126891697] 'applied index is now lower than readState.Index' (duration: 171.474µs)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:56:29.70388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.544975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:56:29.703962Z","caller":"traceutil/trace.go:171","msg":"trace[1316818465] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1269; }","duration":"144.645956ms","start":"2026-06-30T17:56:29.559269Z","end":"2026-06-30T17:56:29.703915Z","steps":["trace[1316818465] 'agreement among raft nodes before linearized reading' (duration: 144.516434ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:56:29.704069Z","caller":"traceutil/trace.go:171","msg":"trace[1927451756] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"494.339038ms","start":"2026-06-30T17:56:29.209699Z","end":"2026-06-30T17:56:29.704038Z","steps":["trace[1927451756] 'process raft request' (duration: 493.869759ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:29.704993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:29.209678Z","time spent":"494.480421ms","remote":"127.0.0.1:41354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-30T17:56:29.910762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.572096ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-30T17:56:29.910896Z","caller":"traceutil/trace.go:171","msg":"trace[1239624735] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1324; }","duration":"149.821967ms","start":"2026-06-30T17:56:29.761051Z","end":"2026-06-30T17:56:29.910872Z","steps":["trace[1239624735] 'read index received' (duration: 35.948385ms)","trace[1239624735] 'applied index is now lower than readState.Index' (duration: 113.871772ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:56:29.911052Z","caller":"traceutil/trace.go:171","msg":"trace[1558818454] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"190.074114ms","start":"2026-06-30T17:56:29.720937Z","end":"2026-06-30T17:56:29.911011Z","steps":["trace[1558818454] 'process raft request' (duration: 76.145672ms)","trace[1558818454] 'compare' (duration: 113.444673ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:56:29.911397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.328758ms","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-06-30T17:56:29.911505Z","caller":"traceutil/trace.go:171","msg":"trace[21295170] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1270; }","duration":"150.49699ms","start":"2026-06-30T17:56:29.760979Z","end":"2026-06-30T17:56:29.911476Z","steps":["trace[21295170] 'agreement among raft nodes before linearized reading' (duration: 150.186273ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:30.0238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.896808ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:26"} {"level":"info","ts":"2026-06-30T17:56:30.024058Z","caller":"traceutil/trace.go:171","msg":"trace[384064886] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1325; }","duration":"113.106117ms","start":"2026-06-30T17:56:29.910932Z","end":"2026-06-30T17:56:30.024038Z","steps":["trace[384064886] 'read index received' (duration: 7.977015ms)","trace[384064886] 'applied index is now lower than readState.Index' (duration: 105.127712ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:56:30.024207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.922835ms","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-30T17:56:30.024242Z","caller":"traceutil/trace.go:171","msg":"trace[46148524] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1270; }","duration":"186.013027ms","start":"2026-06-30T17:56:29.83822Z","end":"2026-06-30T17:56:30.024233Z","steps":["trace[46148524] 'agreement among raft nodes before linearized reading' (duration: 185.876514ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:30.024255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.242932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-30T17:56:30.024267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.311999ms","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-06-30T17:56:30.024338Z","caller":"traceutil/trace.go:171","msg":"trace[817016687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1270; }","duration":"185.353613ms","start":"2026-06-30T17:56:29.838963Z","end":"2026-06-30T17:56:30.024316Z","steps":["trace[817016687] 'agreement among raft nodes before linearized reading' (duration: 185.126569ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:56:30.024352Z","caller":"traceutil/trace.go:171","msg":"trace[212164493] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1270; }","duration":"117.405951ms","start":"2026-06-30T17:56:29.906927Z","end":"2026-06-30T17:56:30.024333Z","steps":["trace[212164493] 'agreement among raft nodes before linearized reading' (duration: 117.160536ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:56:30.025095Z","caller":"traceutil/trace.go:171","msg":"trace[707746531] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"109.59573ms","start":"2026-06-30T17:56:29.915471Z","end":"2026-06-30T17:56:30.025067Z","steps":["trace[707746531] 'process raft request' (duration: 108.433058ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:56:30.292908Z","caller":"traceutil/trace.go:171","msg":"trace[2067705602] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"264.009085ms","start":"2026-06-30T17:56:30.02888Z","end":"2026-06-30T17:56:30.292889Z","steps":["trace[2067705602] 'process raft request' (duration: 263.954604ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:56:30.293025Z","caller":"traceutil/trace.go:171","msg":"trace[1777871136] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"264.192398ms","start":"2026-06-30T17:56:30.028802Z","end":"2026-06-30T17:56:30.292994Z","steps":["trace[1777871136] 'process raft request' (duration: 263.920073ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:56:30.737018Z","caller":"traceutil/trace.go:171","msg":"trace[669680213] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"150.363818ms","start":"2026-06-30T17:56:30.586027Z","end":"2026-06-30T17:56:30.736391Z","steps":["trace[669680213] 'process raft request' (duration: 150.133124ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:56:30.749598Z","caller":"traceutil/trace.go:171","msg":"trace[1453910659] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"145.35107ms","start":"2026-06-30T17:56:30.604212Z","end":"2026-06-30T17:56:30.749563Z","steps":["trace[1453910659] 'process raft request' (duration: 145.278329ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:56:30.749647Z","caller":"traceutil/trace.go:171","msg":"trace[340554286] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"160.124235ms","start":"2026-06-30T17:56:30.589497Z","end":"2026-06-30T17:56:30.749622Z","steps":["trace[340554286] 'process raft request' (duration: 159.794089ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:56:31.359841Z","caller":"traceutil/trace.go:171","msg":"trace[948847769] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"247.332623ms","start":"2026-06-30T17:56:31.112483Z","end":"2026-06-30T17:56:31.359816Z","steps":["trace[948847769] 'process raft request' (duration: 247.196191ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:32.117058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.938834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:56:32.117149Z","caller":"traceutil/trace.go:171","msg":"trace[2008692137] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1282; }","duration":"414.102707ms","start":"2026-06-30T17:56:31.703027Z","end":"2026-06-30T17:56:32.11713Z","steps":["trace[2008692137] 'range keys from in-memory index tree' (duration: 413.801872ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:32.117283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:31.703006Z","time spent":"414.239ms","remote":"127.0.0.1:41268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-30T17:56:32.321138Z","caller":"traceutil/trace.go:171","msg":"trace[1200820327] linearizableReadLoop","detail":"{readStateIndex:1339; appliedIndex:1338; }","duration":"290.889725ms","start":"2026-06-30T17:56:32.03021Z","end":"2026-06-30T17:56:32.321099Z","steps":["trace[1200820327] 'read index received' (duration: 290.391385ms)","trace[1200820327] 'applied index is now lower than readState.Index' (duration: 496.82µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:56:32.321175Z","caller":"traceutil/trace.go:171","msg":"trace[1506020597] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"405.278237ms","start":"2026-06-30T17:56:31.91586Z","end":"2026-06-30T17:56:32.321138Z","steps":["trace[1506020597] 'process raft request' (duration: 404.98606ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:32.321351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:31.915833Z","time spent":"405.42878ms","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T17:56:32.321515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.176523ms","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-30T17:56:32.321564Z","caller":"traceutil/trace.go:171","msg":"trace[1483154248] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1283; }","duration":"227.276584ms","start":"2026-06-30T17:56:32.09427Z","end":"2026-06-30T17:56:32.321546Z","steps":["trace[1483154248] 'agreement among raft nodes before linearized reading' (duration: 227.04147ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:32.321697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.100745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:56:32.32174Z","caller":"traceutil/trace.go:171","msg":"trace[402436062] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1283; }","duration":"101.175535ms","start":"2026-06-30T17:56:32.220549Z","end":"2026-06-30T17:56:32.321724Z","steps":["trace[402436062] 'agreement among raft nodes before linearized reading' (duration: 101.117564ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:32.322249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.022666ms","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-06-30T17:56:32.322325Z","caller":"traceutil/trace.go:171","msg":"trace[618464003] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1283; }","duration":"292.140688ms","start":"2026-06-30T17:56:32.030166Z","end":"2026-06-30T17:56:32.322307Z","steps":["trace[618464003] 'agreement among raft nodes before linearized reading' (duration: 291.061298ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:32.584003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.495054ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-30T17:56:32.58426Z","caller":"traceutil/trace.go:171","msg":"trace[1947030195] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"256.323507ms","start":"2026-06-30T17:56:32.327913Z","end":"2026-06-30T17:56:32.584236Z","steps":["trace[1947030195] 'process raft request' (duration: 95.220601ms)","trace[1947030195] 'compare' (duration: 160.3201ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:56:32.584772Z","caller":"traceutil/trace.go:171","msg":"trace[366811550] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"256.008391ms","start":"2026-06-30T17:56:32.328755Z","end":"2026-06-30T17:56:32.584763Z","steps":["trace[366811550] 'process raft request' (duration: 255.889689ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:56:32.58494Z","caller":"traceutil/trace.go:171","msg":"trace[1894738885] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"256.257675ms","start":"2026-06-30T17:56:32.328632Z","end":"2026-06-30T17:56:32.584889Z","steps":["trace[1894738885] 'process raft request' (duration: 255.546811ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:56:32.584726Z","caller":"traceutil/trace.go:171","msg":"trace[1341002763] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1340; }","duration":"184.539439ms","start":"2026-06-30T17:56:32.400175Z","end":"2026-06-30T17:56:32.584714Z","steps":["trace[1341002763] 'read index received' (duration: 22.866182ms)","trace[1341002763] 'applied index is now lower than readState.Index' (duration: 161.672437ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:56:32.585216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.042238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:56:32.585361Z","caller":"traceutil/trace.go:171","msg":"trace[2037745412] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1287; }","duration":"185.221722ms","start":"2026-06-30T17:56:32.400129Z","end":"2026-06-30T17:56:32.585351Z","steps":["trace[2037745412] 'agreement among raft nodes before linearized reading' (duration: 184.978157ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:37.066825Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920881973035749482,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-30T17:56:37.224825Z","caller":"traceutil/trace.go:171","msg":"trace[2101967480] linearizableReadLoop","detail":"{readStateIndex:1370; appliedIndex:1369; }","duration":"658.862541ms","start":"2026-06-30T17:56:36.565937Z","end":"2026-06-30T17:56:37.2248Z","steps":["trace[2101967480] 'read index received' (duration: 658.651907ms)","trace[2101967480] 'applied index is now lower than readState.Index' (duration: 209.484µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:56:37.224902Z","caller":"traceutil/trace.go:171","msg":"trace[658049001] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"808.110665ms","start":"2026-06-30T17:56:36.416757Z","end":"2026-06-30T17:56:37.224867Z","steps":["trace[658049001] 'process raft request' (duration: 807.82165ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:37.225073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.677577ms","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-06-30T17:56:37.225088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:36.416734Z","time spent":"808.245418ms","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T17:56:37.225109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.101885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:56:37.225119Z","caller":"traceutil/trace.go:171","msg":"trace[1180936760] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1313; }","duration":"626.770139ms","start":"2026-06-30T17:56:36.598335Z","end":"2026-06-30T17:56:37.225105Z","steps":["trace[1180936760] 'agreement among raft nodes before linearized reading' (duration: 626.616846ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:56:37.225284Z","caller":"traceutil/trace.go:171","msg":"trace[1296303811] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1313; }","duration":"659.434111ms","start":"2026-06-30T17:56:36.565823Z","end":"2026-06-30T17:56:37.225257Z","steps":["trace[1296303811] 'agreement among raft nodes before linearized reading' (duration: 659.074484ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:37.22534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:36.598264Z","time spent":"627.064614ms","remote":"127.0.0.1:41552","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-30T17:56:37.225396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:36.565809Z","time spent":"659.554133ms","remote":"127.0.0.1:43382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-06-30T17:56:37.225193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.581383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-30T17:56:37.225735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.891272ms","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-30T17:56:37.22577Z","caller":"traceutil/trace.go:171","msg":"trace[1120313256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1313; }","duration":"626.964133ms","start":"2026-06-30T17:56:36.598797Z","end":"2026-06-30T17:56:37.225762Z","steps":["trace[1120313256] 'agreement among raft nodes before linearized reading' (duration: 626.417231ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:37.225806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:36.598742Z","time spent":"627.055144ms","remote":"127.0.0.1:41552","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-06-30T17:56:37.225652Z","caller":"traceutil/trace.go:171","msg":"trace[742600533] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1313; }","duration":"511.020591ms","start":"2026-06-30T17:56:36.714555Z","end":"2026-06-30T17:56:37.225575Z","steps":["trace[742600533] 'agreement among raft nodes before linearized reading' (duration: 510.576013ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:37.225269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.805552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-30T17:56:37.225197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.597567ms","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-06-30T17:56:37.225904Z","caller":"traceutil/trace.go:171","msg":"trace[1608994058] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1313; }","duration":"396.470026ms","start":"2026-06-30T17:56:36.829416Z","end":"2026-06-30T17:56:37.225886Z","steps":["trace[1608994058] 'agreement among raft nodes before linearized reading' (duration: 395.829153ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:37.225928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:36.714539Z","time spent":"511.348698ms","remote":"127.0.0.1:60680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-06-30T17:56:37.225975Z","caller":"traceutil/trace.go:171","msg":"trace[1193840938] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1313; }","duration":"626.367222ms","start":"2026-06-30T17:56:36.599568Z","end":"2026-06-30T17:56:37.225935Z","steps":["trace[1193840938] 'agreement among raft nodes before linearized reading' (duration: 625.574856ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:37.226072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:36.829396Z","time spent":"396.545398ms","remote":"127.0.0.1:41268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-30T17:56:37.226122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:36.599557Z","time spent":"626.528165ms","remote":"127.0.0.1:41552","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-30T17:56:37.882315Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920881973035749485,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-30T17:56:38.428949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.087280033s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-30T17:56:38.429452Z","caller":"traceutil/trace.go:171","msg":"trace[1531060462] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"1.19826329s","start":"2026-06-30T17:56:37.231167Z","end":"2026-06-30T17:56:38.429431Z","steps":["trace[1531060462] 'process raft request' (duration: 110.392215ms)","trace[1531060462] 'compare' (duration: 1.086989928s)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:56:38.429518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:37.231142Z","time spent":"1.198341761s","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:56:38.429739Z","caller":"traceutil/trace.go:171","msg":"trace[159064051] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"1.197723138s","start":"2026-06-30T17:56:37.232005Z","end":"2026-06-30T17:56:38.429728Z","steps":["trace[159064051] 'process raft request' (duration: 1.197053576s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:38.429794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:37.231987Z","time spent":"1.197777s","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:56:38.430004Z","caller":"traceutil/trace.go:171","msg":"trace[155017257] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"1.197825112s","start":"2026-06-30T17:56:37.232147Z","end":"2026-06-30T17:56:38.429972Z","steps":["trace[155017257] 'process raft request' (duration: 1.196996915s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:38.430177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:37.232113Z","time spent":"1.197972914s","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:56:38.430163Z","caller":"traceutil/trace.go:171","msg":"trace[1614299218] linearizableReadLoop","detail":"{readStateIndex:1373; appliedIndex:1370; }","duration":"1.048342991s","start":"2026-06-30T17:56:37.381791Z","end":"2026-06-30T17:56:38.430134Z","steps":["trace[1614299218] 'read index received' (duration: 867.609596ms)","trace[1614299218] 'applied index is now lower than readState.Index' (duration: 180.731505ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:56:38.43029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02865517s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:56:38.430367Z","caller":"traceutil/trace.go:171","msg":"trace[648253683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1317; }","duration":"1.028748332s","start":"2026-06-30T17:56:37.401604Z","end":"2026-06-30T17:56:38.430353Z","steps":["trace[648253683] 'agreement among raft nodes before linearized reading' (duration: 1.02865618s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:38.430367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.236925ms","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-06-30T17:56:38.43041Z","caller":"traceutil/trace.go:171","msg":"trace[1258166776] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1317; }","duration":"225.381088ms","start":"2026-06-30T17:56:38.205016Z","end":"2026-06-30T17:56:38.430397Z","steps":["trace[1258166776] 'agreement among raft nodes before linearized reading' (duration: 225.252575ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:38.43041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:37.401595Z","time spent":"1.028802893s","remote":"127.0.0.1:41252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-30T17:56:38.430437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048666927s","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-30T17:56:38.430483Z","caller":"traceutil/trace.go:171","msg":"trace[308457196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1317; }","duration":"1.048756139s","start":"2026-06-30T17:56:37.381714Z","end":"2026-06-30T17:56:38.43047Z","steps":["trace[308457196] 'agreement among raft nodes before linearized reading' (duration: 1.048530965s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:38.430475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.297502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-30T17:56:38.430524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:37.381692Z","time spent":"1.04882329s","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-30T17:56:38.430553Z","caller":"traceutil/trace.go:171","msg":"trace[1261832922] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1317; }","duration":"727.426145ms","start":"2026-06-30T17:56:37.703109Z","end":"2026-06-30T17:56:38.430535Z","steps":["trace[1261832922] 'agreement among raft nodes before linearized reading' (duration: 727.16741ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:38.430595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:37.703091Z","time spent":"727.494177ms","remote":"127.0.0.1:41354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":132,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-06-30T17:56:45.567276Z","caller":"traceutil/trace.go:171","msg":"trace[1118585625] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"238.331318ms","start":"2026-06-30T17:56:45.32891Z","end":"2026-06-30T17:56:45.567242Z","steps":["trace[1118585625] 'process raft request' (duration: 238.153244ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:57:03.103111Z","caller":"traceutil/trace.go:171","msg":"trace[1587998942] transaction","detail":"{read_only:false; response_revision:1445; number_of_response:1; }","duration":"481.239745ms","start":"2026-06-30T17:57:02.621839Z","end":"2026-06-30T17:57:03.103079Z","steps":["trace[1587998942] 'process raft request' (duration: 481.02387ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:57:03.103247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.337153ms","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":"info","ts":"2026-06-30T17:57:03.103388Z","caller":"traceutil/trace.go:171","msg":"trace[472725288] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1445; }","duration":"457.509806ms","start":"2026-06-30T17:57:02.64586Z","end":"2026-06-30T17:57:03.103369Z","steps":["trace[472725288] 'agreement among raft nodes before linearized reading' (duration: 457.310183ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:57:03.103467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:57:02.645852Z","time spent":"457.600208ms","remote":"127.0.0.1:41424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-06-30T17:57:03.103487Z","caller":"traceutil/trace.go:171","msg":"trace[1945371053] linearizableReadLoop","detail":"{readStateIndex:1507; appliedIndex:1504; }","duration":"457.120989ms","start":"2026-06-30T17:57:02.645881Z","end":"2026-06-30T17:57:03.103002Z","steps":["trace[1945371053] 'read index received' (duration: 46.751414ms)","trace[1945371053] 'applied index is now lower than readState.Index' (duration: 410.368345ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:57:03.103797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:57:02.621825Z","time spent":"481.366618ms","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:58:51.429743Z","caller":"traceutil/trace.go:171","msg":"trace[1059122019] transaction","detail":"{read_only:false; response_revision:1941; number_of_response:1; }","duration":"173.344767ms","start":"2026-06-30T17:58:51.256377Z","end":"2026-06-30T17:58:51.429722Z","steps":["trace[1059122019] 'process raft request' (duration: 106.233585ms)","trace[1059122019] 'compare' (duration: 66.737955ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:58:51.429793Z","caller":"traceutil/trace.go:171","msg":"trace[1002112205] transaction","detail":"{read_only:false; response_revision:1942; number_of_response:1; }","duration":"101.074236ms","start":"2026-06-30T17:58:51.328689Z","end":"2026-06-30T17:58:51.429763Z","steps":["trace[1002112205] 'process raft request' (duration: 100.77101ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:58:51.691849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.581297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-06-30T17:58:51.692007Z","caller":"traceutil/trace.go:171","msg":"trace[200534502] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1942; }","duration":"155.74397ms","start":"2026-06-30T17:58:51.536244Z","end":"2026-06-30T17:58:51.691988Z","steps":["trace[200534502] 'range keys from in-memory index tree' (duration: 155.478904ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:00:22.047198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.639604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-06-30T18:00:22.047286Z","caller":"traceutil/trace.go:171","msg":"trace[1633532326] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2383; }","duration":"117.766115ms","start":"2026-06-30T18:00:21.929498Z","end":"2026-06-30T18:00:22.047265Z","steps":["trace[1633532326] 'range keys from in-memory index tree' (duration: 117.44667ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:00:22.742923Z","caller":"traceutil/trace.go:171","msg":"trace[246676212] transaction","detail":"{read_only:false; response_revision:2386; number_of_response:1; }","duration":"100.981303ms","start":"2026-06-30T18:00:22.641917Z","end":"2026-06-30T18:00:22.742898Z","steps":["trace[246676212] 'process raft request' (duration: 100.84793ms)"],"step_count":1}