{"level":"warn","ts":"2026-07-06T22:14:41.452662Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-06T22:14:41.452771Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.157: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.157:2380","--initial-cluster=instance=https://199.204.45.157:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.157:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.157:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-06T22:14:41.452848Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-06T22:14:41.452861Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-07-06T22:14:41.452881Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-06T22:14:41.453836Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"]} {"level":"info","ts":"2026-07-06T22:14:41.453931Z","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.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.157:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-06T22:14:41.457449Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.228997ms"} {"level":"info","ts":"2026-07-06T22:14:41.464215Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f170c75a1d97cc","cluster-id":"44a3176ec85feb9"} {"level":"info","ts":"2026-07-06T22:14:41.464427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=()"} {"level":"info","ts":"2026-07-06T22:14:41.464461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 0"} {"level":"info","ts":"2026-07-06T22:14:41.464478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f170c75a1d97cc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-06T22:14:41.464495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 1"} {"level":"info","ts":"2026-07-06T22:14:41.464541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"warn","ts":"2026-07-06T22:14:41.46923Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-06T22:14:41.472623Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-06T22:14:41.474166Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-06T22:14:41.476928Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f170c75a1d97cc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-06T22:14:41.477108Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f170c75a1d97cc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-06T22:14:41.477214Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-06T22:14:41.477264Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-06T22:14:41.477273Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-06T22:14:41.477927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"info","ts":"2026-07-06T22:14:41.478038Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","added-peer-id":"5f170c75a1d97cc","added-peer-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-07-06T22:14:41.481234Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-06T22:14:41.481315Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-07-06T22:14:41.481342Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-07-06T22:14:41.482193Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f170c75a1d97cc","initial-advertise-peer-urls":["https://199.204.45.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-06T22:14:41.482223Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-06T22:14:42.065196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc is starting a new election at term 1"} {"level":"info","ts":"2026-07-06T22:14:42.065268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became pre-candidate at term 1"} {"level":"info","ts":"2026-07-06T22:14:42.065321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgPreVoteResp from 5f170c75a1d97cc at term 1"} {"level":"info","ts":"2026-07-06T22:14:42.065345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became candidate at term 2"} {"level":"info","ts":"2026-07-06T22:14:42.065374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgVoteResp from 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-07-06T22:14:42.065394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became leader at term 2"} {"level":"info","ts":"2026-07-06T22:14:42.06541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f170c75a1d97cc elected leader 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-07-06T22:14:42.067427Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f170c75a1d97cc","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.157:2379]}","request-path":"/0/members/5f170c75a1d97cc/attributes","cluster-id":"44a3176ec85feb9","publish-timeout":"7s"} {"level":"info","ts":"2026-07-06T22:14:42.06746Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-06T22:14:42.067776Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T22:14:42.06788Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-06T22:14:42.068269Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-06T22:14:42.068306Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-06T22:14:42.069858Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.157:2379"} {"level":"info","ts":"2026-07-06T22:14:42.070875Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-06T22:14:42.074712Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T22:14:42.074758Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T22:14:42.074773Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T22:14:57.836385Z","caller":"traceutil/trace.go:171","msg":"trace[1012662344] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:379; }","duration":"187.648353ms","start":"2026-07-06T22:14:57.648715Z","end":"2026-07-06T22:14:57.836363Z","steps":["trace[1012662344] 'read index received' (duration: 187.484189ms)","trace[1012662344] 'applied index is now lower than readState.Index' (duration: 163.314µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:14:57.83647Z","caller":"traceutil/trace.go:171","msg":"trace[2080729067] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"304.326867ms","start":"2026-07-06T22:14:57.532117Z","end":"2026-07-06T22:14:57.836443Z","steps":["trace[2080729067] 'process raft request' (duration: 304.133133ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:14:57.836869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.057544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:14:57.836989Z","caller":"traceutil/trace.go:171","msg":"trace[2121595911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:367; }","duration":"188.31027ms","start":"2026-07-06T22:14:57.648659Z","end":"2026-07-06T22:14:57.83697Z","steps":["trace[2121595911] 'agreement among raft nodes before linearized reading' (duration: 187.898459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:14:57.83766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:14:57.532104Z","time spent":"304.715828ms","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:14:58.170104Z","caller":"traceutil/trace.go:171","msg":"trace[1545069255] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:380; }","duration":"279.719824ms","start":"2026-07-06T22:14:57.890355Z","end":"2026-07-06T22:14:58.170075Z","steps":["trace[1545069255] 'read index received' (duration: 279.55848ms)","trace[1545069255] 'applied index is now lower than readState.Index' (duration: 159.824µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:14:58.170173Z","caller":"traceutil/trace.go:171","msg":"trace[111968606] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"325.120623ms","start":"2026-07-06T22:14:57.844992Z","end":"2026-07-06T22:14:58.170112Z","steps":["trace[111968606] 'process raft request' (duration: 324.930378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:14:58.170331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.939359ms","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-07-06T22:14:58.170578Z","caller":"traceutil/trace.go:171","msg":"trace[1064977773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:368; }","duration":"280.221667ms","start":"2026-07-06T22:14:57.890328Z","end":"2026-07-06T22:14:58.17055Z","steps":["trace[1064977773] 'agreement among raft nodes before linearized reading' (duration: 279.902249ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:14:58.170539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:14:57.844964Z","time spent":"325.457691ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5149,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T22:14:58.653686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.892048ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T22:14:58.653773Z","caller":"traceutil/trace.go:171","msg":"trace[2134355463] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:381; }","duration":"253.429466ms","start":"2026-07-06T22:14:58.40033Z","end":"2026-07-06T22:14:58.653759Z","steps":["trace[2134355463] 'read index received' (duration: 36.091µs)","trace[2134355463] 'applied index is now lower than readState.Index' (duration: 253.392365ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:14:58.653824Z","caller":"traceutil/trace.go:171","msg":"trace[408690100] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"478.905659ms","start":"2026-07-06T22:14:58.17487Z","end":"2026-07-06T22:14:58.653776Z","steps":["trace[408690100] 'process raft request' (duration: 193.429315ms)","trace[408690100] 'compare' (duration: 284.771956ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:14:58.653964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:14:58.174852Z","time spent":"479.041822ms","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T22:14:58.654047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.688222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-06T22:14:58.654083Z","caller":"traceutil/trace.go:171","msg":"trace[604330277] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:369; }","duration":"253.783784ms","start":"2026-07-06T22:14:58.400289Z","end":"2026-07-06T22:14:58.654073Z","steps":["trace[604330277] 'agreement among raft nodes before linearized reading' (duration: 253.61591ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:15:08.069302Z","caller":"traceutil/trace.go:171","msg":"trace[1593996313] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"191.610437ms","start":"2026-07-06T22:15:07.877665Z","end":"2026-07-06T22:15:08.069275Z","steps":["trace[1593996313] 'process raft request' (duration: 191.446672ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:15:21.003208Z","caller":"traceutil/trace.go:171","msg":"trace[2003200556] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"217.395563ms","start":"2026-07-06T22:15:20.783373Z","end":"2026-07-06T22:15:21.000768Z","steps":["trace[2003200556] 'process raft request' (duration: 216.878129ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:15:21.003113Z","caller":"traceutil/trace.go:171","msg":"trace[1611351652] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"279.2072ms","start":"2026-07-06T22:15:20.723248Z","end":"2026-07-06T22:15:21.002456Z","steps":["trace[1611351652] 'process raft request' (duration: 271.474108ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:15:21.003652Z","caller":"traceutil/trace.go:171","msg":"trace[724848347] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"231.284535ms","start":"2026-07-06T22:15:20.771164Z","end":"2026-07-06T22:15:21.002448Z","steps":["trace[724848347] 'read index received' (duration: 222.575418ms)","trace[724848347] 'applied index is now lower than readState.Index' (duration: 7.040304ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:15:21.004418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.14002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:15:21.004454Z","caller":"traceutil/trace.go:171","msg":"trace[117107577] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"217.768582ms","start":"2026-07-06T22:15:20.78667Z","end":"2026-07-06T22:15:21.004439Z","steps":["trace[117107577] 'process raft request' (duration: 213.673944ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:15:21.00457Z","caller":"traceutil/trace.go:171","msg":"trace[417284162] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:580; }","duration":"124.245723ms","start":"2026-07-06T22:15:20.880244Z","end":"2026-07-06T22:15:21.004489Z","steps":["trace[417284162] 'agreement among raft nodes before linearized reading' (duration: 124.042668ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:15:21.004743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.569785ms","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-07-06T22:15:21.00479Z","caller":"traceutil/trace.go:171","msg":"trace[2029119339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"233.644267ms","start":"2026-07-06T22:15:20.771134Z","end":"2026-07-06T22:15:21.004778Z","steps":["trace[2029119339] 'agreement among raft nodes before linearized reading' (duration: 229.679644ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:15:21.00482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.55633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:15:21.005059Z","caller":"traceutil/trace.go:171","msg":"trace[473945307] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:580; }","duration":"124.833009ms","start":"2026-07-06T22:15:20.880194Z","end":"2026-07-06T22:15:21.005027Z","steps":["trace[473945307] 'agreement among raft nodes before linearized reading' (duration: 124.045539ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:15:27.029866Z","caller":"traceutil/trace.go:171","msg":"trace[1259479858] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"100.967173ms","start":"2026-07-06T22:15:26.92887Z","end":"2026-07-06T22:15:27.029837Z","steps":["trace[1259479858] 'process raft request' (duration: 100.236874ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:15:27.147371Z","caller":"traceutil/trace.go:171","msg":"trace[975541741] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"112.186107ms","start":"2026-07-06T22:15:27.035146Z","end":"2026-07-06T22:15:27.147333Z","steps":["trace[975541741] 'process raft request' (duration: 24.786208ms)","trace[975541741] 'compare' (duration: 87.258005ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:15:27.614247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.654231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4r98v\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-06T22:15:27.614431Z","caller":"traceutil/trace.go:171","msg":"trace[585077946] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4r98v; range_end:; response_count:1; response_revision:636; }","duration":"143.896097ms","start":"2026-07-06T22:15:27.470488Z","end":"2026-07-06T22:15:27.614384Z","steps":["trace[585077946] 'range keys from in-memory index tree' (duration: 142.657725ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:15:28.140997Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938292664276237766,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T22:15:28.642139Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938292664276237766,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T22:15:28.911581Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.272571789s","expected-duration":"1s"} {"level":"info","ts":"2026-07-06T22:15:28.995338Z","caller":"traceutil/trace.go:171","msg":"trace[1556665299] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.347879671s","start":"2026-07-06T22:15:27.647412Z","end":"2026-07-06T22:15:28.995291Z","steps":["trace[1556665299] 'process raft request' (duration: 1.347673796s)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:15:28.995412Z","caller":"traceutil/trace.go:171","msg":"trace[1321901478] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.299068163s","start":"2026-07-06T22:15:27.696318Z","end":"2026-07-06T22:15:28.995386Z","steps":["trace[1321901478] 'process raft request' (duration: 1.298892499s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:15:28.995536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:15:27.696286Z","time spent":"1.299186936s","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T22:15:28.995758Z","caller":"traceutil/trace.go:171","msg":"trace[390602465] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:666; }","duration":"1.355019138s","start":"2026-07-06T22:15:27.640278Z","end":"2026-07-06T22:15:28.995297Z","steps":["trace[390602465] 'read index received' (duration: 1.271741788s)","trace[390602465] 'applied index is now lower than readState.Index' (duration: 83.27628ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:15:28.995933Z","caller":"traceutil/trace.go:171","msg":"trace[1418796863] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"1.209146348s","start":"2026-07-06T22:15:27.786775Z","end":"2026-07-06T22:15:28.995921Z","steps":["trace[1418796863] 'process raft request' (duration: 1.208479401s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:15:28.996053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:15:27.786749Z","time spent":"1.20923229s","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T22:15:28.996163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.39409983s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-06T22:15:28.996202Z","caller":"traceutil/trace.go:171","msg":"trace[1213469159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:641; }","duration":"1.394166132s","start":"2026-07-06T22:15:27.602025Z","end":"2026-07-06T22:15:28.996191Z","steps":["trace[1213469159] 'agreement among raft nodes before linearized reading' (duration: 1.394043689s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:15:28.996231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:15:27.602008Z","time spent":"1.394214773s","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-06T22:15:28.996419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.24339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-06T22:15:28.996463Z","caller":"traceutil/trace.go:171","msg":"trace[1868246206] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:641; }","duration":"947.324003ms","start":"2026-07-06T22:15:28.049126Z","end":"2026-07-06T22:15:28.99645Z","steps":["trace[1868246206] 'agreement among raft nodes before linearized reading' (duration: 947.182499ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:15:28.996496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:15:28.049106Z","time spent":"947.382095ms","remote":"127.0.0.1:44092","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-07-06T22:15:28.997618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:15:27.647383Z","time spent":"1.348054294s","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:15:28.997824Z","caller":"traceutil/trace.go:171","msg":"trace[505564967] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"1.361493417s","start":"2026-07-06T22:15:27.633737Z","end":"2026-07-06T22:15:28.99523Z","steps":["trace[505564967] 'process raft request' (duration: 1.278297569s)","trace[505564967] 'compare' (duration: 82.771197ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:15:28.997959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:15:27.633682Z","time spent":"1.364237899s","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T22:15:28.996111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.347303975s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T22:15:28.998222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.605536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-06T22:15:28.99836Z","caller":"traceutil/trace.go:171","msg":"trace[1615683716] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:641; }","duration":"838.78327ms","start":"2026-07-06T22:15:28.159566Z","end":"2026-07-06T22:15:28.99835Z","steps":["trace[1615683716] 'agreement among raft nodes before linearized reading' (duration: 836.440049ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:15:28.99831Z","caller":"traceutil/trace.go:171","msg":"trace[1637199946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"1.349398289s","start":"2026-07-06T22:15:27.648754Z","end":"2026-07-06T22:15:28.998152Z","steps":["trace[1637199946] 'agreement among raft nodes before linearized reading' (duration: 1.347179221s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:15:28.998442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:15:27.648744Z","time spent":"1.349693288s","remote":"127.0.0.1:43832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-06T22:15:28.998395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:15:28.159524Z","time spent":"838.862162ms","remote":"127.0.0.1:44092","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":"info","ts":"2026-07-06T22:15:41.779024Z","caller":"traceutil/trace.go:171","msg":"trace[1865882994] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:781; }","duration":"130.129438ms","start":"2026-07-06T22:15:41.648853Z","end":"2026-07-06T22:15:41.778983Z","steps":["trace[1865882994] 'read index received' (duration: 128.30422ms)","trace[1865882994] 'applied index is now lower than readState.Index' (duration: 1.823658ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:15:41.779259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.377514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:15:41.779248Z","caller":"traceutil/trace.go:171","msg":"trace[2058598822] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"197.160632ms","start":"2026-07-06T22:15:41.582053Z","end":"2026-07-06T22:15:41.779214Z","steps":["trace[2058598822] 'process raft request' (duration: 195.089297ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:15:41.779321Z","caller":"traceutil/trace.go:171","msg":"trace[2075532472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:749; }","duration":"130.498977ms","start":"2026-07-06T22:15:41.648808Z","end":"2026-07-06T22:15:41.779307Z","steps":["trace[2075532472] 'agreement among raft nodes before linearized reading' (duration: 130.321012ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:15:41.784333Z","caller":"traceutil/trace.go:171","msg":"trace[303001150] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"127.928578ms","start":"2026-07-06T22:15:41.656374Z","end":"2026-07-06T22:15:41.784302Z","steps":["trace[303001150] 'process raft request' (duration: 127.707253ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:15:45.149564Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938292664276238000,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-06T22:15:45.554166Z","caller":"traceutil/trace.go:171","msg":"trace[128700569] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"975.298855ms","start":"2026-07-06T22:15:44.57884Z","end":"2026-07-06T22:15:45.554139Z","steps":["trace[128700569] 'process raft request' (duration: 975.041989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:15:45.554471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:15:44.578817Z","time spent":"975.427329ms","remote":"127.0.0.1:44064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:15:45.576503Z","caller":"traceutil/trace.go:171","msg":"trace[1945236810] linearizableReadLoop","detail":"{readStateIndex:799; appliedIndex:797; }","duration":"927.632748ms","start":"2026-07-06T22:15:44.648858Z","end":"2026-07-06T22:15:45.57649Z","steps":["trace[1945236810] 'read index received' (duration: 904.988255ms)","trace[1945236810] 'applied index is now lower than readState.Index' (duration: 22.643863ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:15:45.576817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.354795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-06T22:15:45.576932Z","caller":"traceutil/trace.go:171","msg":"trace[767289151] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:1; response_revision:766; }","duration":"546.525969ms","start":"2026-07-06T22:15:45.030395Z","end":"2026-07-06T22:15:45.576921Z","steps":["trace[767289151] 'agreement among raft nodes before linearized reading' (duration: 546.260712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:15:45.576988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:15:45.030375Z","time spent":"546.603932ms","remote":"127.0.0.1:43872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.157\" "} {"level":"warn","ts":"2026-07-06T22:15:45.576982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.708514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-06T22:15:45.57712Z","caller":"traceutil/trace.go:171","msg":"trace[1794575479] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:766; }","duration":"504.858439ms","start":"2026-07-06T22:15:45.072247Z","end":"2026-07-06T22:15:45.577106Z","steps":["trace[1794575479] 'agreement among raft nodes before linearized reading' (duration: 504.666884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:15:45.577125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.678291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-06T22:15:45.577257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.840313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-06T22:15:45.577261Z","caller":"traceutil/trace.go:171","msg":"trace[1557200819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:766; }","duration":"248.838305ms","start":"2026-07-06T22:15:45.3284Z","end":"2026-07-06T22:15:45.577238Z","steps":["trace[1557200819] 'agreement among raft nodes before linearized reading' (duration: 248.473946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:15:45.577144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.903909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-06T22:15:45.5773Z","caller":"traceutil/trace.go:171","msg":"trace[1691492524] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:766; }","duration":"505.912415ms","start":"2026-07-06T22:15:45.071372Z","end":"2026-07-06T22:15:45.577284Z","steps":["trace[1691492524] 'agreement among raft nodes before linearized reading' (duration: 505.821153ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:15:45.577324Z","caller":"traceutil/trace.go:171","msg":"trace[658822925] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:766; }","duration":"505.126865ms","start":"2026-07-06T22:15:45.07219Z","end":"2026-07-06T22:15:45.577317Z","steps":["trace[658822925] 'agreement among raft nodes before linearized reading' (duration: 504.712345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:15:45.577328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:15:45.071351Z","time spent":"505.970447ms","remote":"127.0.0.1:44092","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-07-06T22:15:45.577352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:15:45.072171Z","time spent":"505.172046ms","remote":"127.0.0.1:44092","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-07-06T22:15:45.576734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.878663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:15:45.576591Z","caller":"traceutil/trace.go:171","msg":"trace[162981529] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"686.685539ms","start":"2026-07-06T22:15:44.889878Z","end":"2026-07-06T22:15:45.576564Z","steps":["trace[162981529] 'process raft request' (duration: 686.552676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:15:45.577421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.21101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-06T22:15:45.577503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:15:44.88985Z","time spent":"687.592442ms","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:15:45.577387Z","caller":"traceutil/trace.go:171","msg":"trace[395568860] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:766; }","duration":"928.573871ms","start":"2026-07-06T22:15:44.648802Z","end":"2026-07-06T22:15:45.577376Z","steps":["trace[395568860] 'agreement among raft nodes before linearized reading' (duration: 927.795471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:15:45.577552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:15:44.64878Z","time spent":"928.763218ms","remote":"127.0.0.1:43832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-06T22:15:45.577154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:15:45.072242Z","time spent":"504.902ms","remote":"127.0.0.1:44092","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":"info","ts":"2026-07-06T22:15:45.577508Z","caller":"traceutil/trace.go:171","msg":"trace[1270503181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:766; }","duration":"502.849245ms","start":"2026-07-06T22:15:45.074597Z","end":"2026-07-06T22:15:45.577446Z","steps":["trace[1270503181] 'agreement among raft nodes before linearized reading' (duration: 502.125366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:15:45.578131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:15:45.074585Z","time spent":"503.530454ms","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-06T22:15:47.71515Z","caller":"traceutil/trace.go:171","msg":"trace[1452356369] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"109.085387ms","start":"2026-07-06T22:15:47.606043Z","end":"2026-07-06T22:15:47.715129Z","steps":["trace[1452356369] 'process raft request' (duration: 108.941603ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:15:47.715484Z","caller":"traceutil/trace.go:171","msg":"trace[676721433] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"108.499011ms","start":"2026-07-06T22:15:47.606972Z","end":"2026-07-06T22:15:47.715471Z","steps":["trace[676721433] 'process raft request' (duration: 108.094301ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:15:47.715551Z","caller":"traceutil/trace.go:171","msg":"trace[486570672] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"107.35532ms","start":"2026-07-06T22:15:47.608173Z","end":"2026-07-06T22:15:47.715529Z","steps":["trace[486570672] 'process raft request' (duration: 107.308569ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:15:47.715762Z","caller":"traceutil/trace.go:171","msg":"trace[1026126516] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"108.137442ms","start":"2026-07-06T22:15:47.607613Z","end":"2026-07-06T22:15:47.715751Z","steps":["trace[1026126516] 'process raft request' (duration: 107.830573ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:15:47.715577Z","caller":"traceutil/trace.go:171","msg":"trace[938521783] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"108.45991ms","start":"2026-07-06T22:15:47.607094Z","end":"2026-07-06T22:15:47.715554Z","steps":["trace[938521783] 'process raft request' (duration: 108.212853ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:15:48.770295Z","caller":"traceutil/trace.go:171","msg":"trace[2027849474] linearizableReadLoop","detail":"{readStateIndex:816; appliedIndex:815; }","duration":"120.800303ms","start":"2026-07-06T22:15:48.649461Z","end":"2026-07-06T22:15:48.770261Z","steps":["trace[2027849474] 'read index received' (duration: 120.460555ms)","trace[2027849474] 'applied index is now lower than readState.Index' (duration: 338.458µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:15:48.770479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.975047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:15:48.770526Z","caller":"traceutil/trace.go:171","msg":"trace[270650094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:782; }","duration":"121.09476ms","start":"2026-07-06T22:15:48.649419Z","end":"2026-07-06T22:15:48.770514Z","steps":["trace[270650094] 'agreement among raft nodes before linearized reading' (duration: 120.975447ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:15:48.770794Z","caller":"traceutil/trace.go:171","msg":"trace[1328161311] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"178.685259ms","start":"2026-07-06T22:15:48.592078Z","end":"2026-07-06T22:15:48.770764Z","steps":["trace[1328161311] 'process raft request' (duration: 177.941539ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:15:53.921969Z","caller":"traceutil/trace.go:171","msg":"trace[1748070770] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"117.891618ms","start":"2026-07-06T22:15:53.804055Z","end":"2026-07-06T22:15:53.921946Z","steps":["trace[1748070770] 'process raft request' (duration: 117.727593ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:15:53.92207Z","caller":"traceutil/trace.go:171","msg":"trace[525331159] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"113.646926ms","start":"2026-07-06T22:15:53.808417Z","end":"2026-07-06T22:15:53.922064Z","steps":["trace[525331159] 'process raft request' (duration: 113.509992ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:15:53.922016Z","caller":"traceutil/trace.go:171","msg":"trace[1130769598] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"113.547184ms","start":"2026-07-06T22:15:53.80845Z","end":"2026-07-06T22:15:53.921998Z","steps":["trace[1130769598] 'process raft request' (duration: 113.501653ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:15:53.922045Z","caller":"traceutil/trace.go:171","msg":"trace[2062683056] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"113.693477ms","start":"2026-07-06T22:15:53.808333Z","end":"2026-07-06T22:15:53.922026Z","steps":["trace[2062683056] 'process raft request' (duration: 113.550533ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:15:53.922334Z","caller":"traceutil/trace.go:171","msg":"trace[1037634520] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"113.696046ms","start":"2026-07-06T22:15:53.808621Z","end":"2026-07-06T22:15:53.922317Z","steps":["trace[1037634520] 'process raft request' (duration: 113.352077ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:21.43245Z","caller":"traceutil/trace.go:171","msg":"trace[1771498682] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"201.324184ms","start":"2026-07-06T22:16:21.231108Z","end":"2026-07-06T22:16:21.432432Z","steps":["trace[1771498682] 'process raft request' (duration: 201.18865ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:28.181904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.671345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-06T22:16:28.181963Z","caller":"traceutil/trace.go:171","msg":"trace[440808196] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1004; }","duration":"103.777659ms","start":"2026-07-06T22:16:28.078171Z","end":"2026-07-06T22:16:28.181949Z","steps":["trace[440808196] 'range keys from in-memory index tree' (duration: 103.507962ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:28.182248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.245518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-06T22:16:28.182316Z","caller":"traceutil/trace.go:171","msg":"trace[127317350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1004; }","duration":"110.37659ms","start":"2026-07-06T22:16:28.071924Z","end":"2026-07-06T22:16:28.182301Z","steps":["trace[127317350] 'range keys from in-memory index tree' (duration: 110.078693ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:28.182281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.6994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-06T22:16:28.182368Z","caller":"traceutil/trace.go:171","msg":"trace[215018039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1004; }","duration":"110.851933ms","start":"2026-07-06T22:16:28.071498Z","end":"2026-07-06T22:16:28.18235Z","steps":["trace[215018039] 'range keys from in-memory index tree' (duration: 110.442813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:28.182471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.011068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-06T22:16:28.182633Z","caller":"traceutil/trace.go:171","msg":"trace[418373229] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1004; }","duration":"119.234694ms","start":"2026-07-06T22:16:28.063368Z","end":"2026-07-06T22:16:28.182602Z","steps":["trace[418373229] 'range keys from in-memory index tree' (duration: 118.75163ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:28.326026Z","caller":"traceutil/trace.go:171","msg":"trace[1009601965] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1049; }","duration":"109.487078ms","start":"2026-07-06T22:16:28.216513Z","end":"2026-07-06T22:16:28.326Z","steps":["trace[1009601965] 'read index received' (duration: 35.493229ms)","trace[1009601965] 'applied index is now lower than readState.Index' (duration: 73.992689ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:16:28.326078Z","caller":"traceutil/trace.go:171","msg":"trace[1345311303] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"138.811597ms","start":"2026-07-06T22:16:28.187222Z","end":"2026-07-06T22:16:28.326033Z","steps":["trace[1345311303] 'process raft request' (duration: 94.693581ms)","trace[1345311303] 'compare' (duration: 43.832877ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:16:28.326523Z","caller":"traceutil/trace.go:171","msg":"trace[940402106] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"138.914219ms","start":"2026-07-06T22:16:28.18757Z","end":"2026-07-06T22:16:28.326484Z","steps":["trace[940402106] 'process raft request' (duration: 138.384966ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:28.326518Z","caller":"traceutil/trace.go:171","msg":"trace[1552729175] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"139.165775ms","start":"2026-07-06T22:16:28.187319Z","end":"2026-07-06T22:16:28.326485Z","steps":["trace[1552729175] 'process raft request' (duration: 138.5939ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:28.326892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.720214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:16:28.326948Z","caller":"traceutil/trace.go:171","msg":"trace[1141080053] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1008; }","duration":"110.464024ms","start":"2026-07-06T22:16:28.216467Z","end":"2026-07-06T22:16:28.326931Z","steps":["trace[1141080053] 'agreement among raft nodes before linearized reading' (duration: 109.633402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:40.634452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.582787ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-06T22:16:41.714691Z","caller":"traceutil/trace.go:171","msg":"trace[2066034844] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"153.435699ms","start":"2026-07-06T22:16:41.561223Z","end":"2026-07-06T22:16:41.714659Z","steps":["trace[2066034844] 'process raft request' (duration: 153.265773ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:46.11981Z","caller":"traceutil/trace.go:171","msg":"trace[2019985562] linearizableReadLoop","detail":"{readStateIndex:1111; appliedIndex:1110; }","duration":"290.178741ms","start":"2026-07-06T22:16:45.82961Z","end":"2026-07-06T22:16:46.119789Z","steps":["trace[2019985562] 'read index received' (duration: 289.986616ms)","trace[2019985562] 'applied index is now lower than readState.Index' (duration: 191.175µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:16:46.119891Z","caller":"traceutil/trace.go:171","msg":"trace[1493681962] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"395.562681ms","start":"2026-07-06T22:16:45.724293Z","end":"2026-07-06T22:16:46.119856Z","steps":["trace[1493681962] 'process raft request' (duration: 395.379477ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:46.120029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.675513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-06T22:16:46.120071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:16:45.724266Z","time spent":"395.702684ms","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:16:46.120078Z","caller":"traceutil/trace.go:171","msg":"trace[968234844] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1062; }","duration":"248.758505ms","start":"2026-07-06T22:16:45.871308Z","end":"2026-07-06T22:16:46.120066Z","steps":["trace[968234844] 'agreement among raft nodes before linearized reading' (duration: 248.677213ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:46.120031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.400217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-06T22:16:46.120367Z","caller":"traceutil/trace.go:171","msg":"trace[881189840] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1062; }","duration":"290.774386ms","start":"2026-07-06T22:16:45.829568Z","end":"2026-07-06T22:16:46.120343Z","steps":["trace[881189840] 'agreement among raft nodes before linearized reading' (duration: 290.401356ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:46.250374Z","caller":"traceutil/trace.go:171","msg":"trace[906834724] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"125.98965ms","start":"2026-07-06T22:16:46.124358Z","end":"2026-07-06T22:16:46.250348Z","steps":["trace[906834724] 'process raft request' (duration: 91.698192ms)","trace[906834724] 'compare' (duration: 34.178165ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:16:48.05317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.403463061s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T22:16:48.053327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.61493216s","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-07-06T22:16:48.053367Z","caller":"traceutil/trace.go:171","msg":"trace[748216520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1063; }","duration":"1.615020773s","start":"2026-07-06T22:16:46.438333Z","end":"2026-07-06T22:16:48.053354Z","steps":["trace[748216520] 'range keys from in-memory index tree' (duration: 1.614811437s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:48.053394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:16:46.438313Z","time spent":"1.615075934s","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-06T22:16:48.053328Z","caller":"traceutil/trace.go:171","msg":"trace[596083799] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1063; }","duration":"1.403653046s","start":"2026-07-06T22:16:46.649655Z","end":"2026-07-06T22:16:48.053308Z","steps":["trace[596083799] 'range keys from in-memory index tree' (duration: 1.403399268s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:48.053527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:16:46.64964Z","time spent":"1.403871772s","remote":"127.0.0.1:43832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-06T22:16:48.053609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.58630804s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-06T22:16:48.053635Z","caller":"traceutil/trace.go:171","msg":"trace[1778722981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1063; }","duration":"1.586368452s","start":"2026-07-06T22:16:46.467259Z","end":"2026-07-06T22:16:48.053627Z","steps":["trace[1778722981] 'range keys from in-memory index tree' (duration: 1.586178238s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:48.053652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:16:46.467243Z","time spent":"1.586404663s","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-06T22:16:48.053845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.52901283s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-06T22:16:48.053869Z","caller":"traceutil/trace.go:171","msg":"trace[152820734] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1063; }","duration":"1.529082522s","start":"2026-07-06T22:16:46.524781Z","end":"2026-07-06T22:16:48.053863Z","steps":["trace[152820734] 'range keys from in-memory index tree' (duration: 1.528874415s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:48.053886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:16:46.524752Z","time spent":"1.529129292s","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-06T22:16:48.053232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.675477266s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-06T22:16:48.054017Z","caller":"traceutil/trace.go:171","msg":"trace[1847986069] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1063; }","duration":"1.676300428s","start":"2026-07-06T22:16:46.37769Z","end":"2026-07-06T22:16:48.05399Z","steps":["trace[1847986069] 'range keys from in-memory index tree' (duration: 1.675332232s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:48.054103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:16:46.37767Z","time spent":"1.676404591s","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-06T22:16:48.056342Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.105126417s","expected-duration":"1s"} {"level":"info","ts":"2026-07-06T22:16:48.056779Z","caller":"traceutil/trace.go:171","msg":"trace[127334913] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"1.105702082s","start":"2026-07-06T22:16:46.951057Z","end":"2026-07-06T22:16:48.056759Z","steps":["trace[127334913] 'process raft request' (duration: 1.105435605s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:48.056952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:16:46.951033Z","time spent":"1.105827486s","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:16:56.52788Z","caller":"traceutil/trace.go:171","msg":"trace[267439763] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"390.461657ms","start":"2026-07-06T22:16:56.137392Z","end":"2026-07-06T22:16:56.527853Z","steps":["trace[267439763] 'process raft request' (duration: 390.237401ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:56.528064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:16:56.137384Z","time spent":"390.589071ms","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:16:56.528074Z","caller":"traceutil/trace.go:171","msg":"trace[215705958] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"388.921698ms","start":"2026-07-06T22:16:56.13911Z","end":"2026-07-06T22:16:56.528031Z","steps":["trace[215705958] 'process raft request' (duration: 388.677221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:56.528251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:16:56.139102Z","time spent":"389.038ms","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:16:56.540679Z","caller":"traceutil/trace.go:171","msg":"trace[241552620] linearizableReadLoop","detail":"{readStateIndex:1146; appliedIndex:1143; }","duration":"221.951803ms","start":"2026-07-06T22:16:56.31871Z","end":"2026-07-06T22:16:56.540662Z","steps":["trace[241552620] 'read index received' (duration: 208.919272ms)","trace[241552620] 'applied index is now lower than readState.Index' (duration: 13.031641ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:16:56.54091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.20008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-06T22:16:56.54096Z","caller":"traceutil/trace.go:171","msg":"trace[130681662] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1095; }","duration":"222.289862ms","start":"2026-07-06T22:16:56.318656Z","end":"2026-07-06T22:16:56.540946Z","steps":["trace[130681662] 'agreement among raft nodes before linearized reading' (duration: 222.110408ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:56.540719Z","caller":"traceutil/trace.go:171","msg":"trace[1455055958] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"285.904968ms","start":"2026-07-06T22:16:56.254798Z","end":"2026-07-06T22:16:56.540703Z","steps":["trace[1455055958] 'process raft request' (duration: 285.761715ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:58.604909Z","caller":"traceutil/trace.go:171","msg":"trace[2101494212] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"222.536659ms","start":"2026-07-06T22:16:58.382335Z","end":"2026-07-06T22:16:58.604872Z","steps":["trace[2101494212] 'process raft request' (duration: 222.298953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:00.941465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.421984ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T22:17:00.941707Z","caller":"traceutil/trace.go:171","msg":"trace[695951044] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"211.785328ms","start":"2026-07-06T22:17:00.729763Z","end":"2026-07-06T22:17:00.941548Z","steps":["trace[695951044] 'process raft request' (duration: 67.20259ms)","trace[695951044] 'compare' (duration: 144.108915ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:17:04.092776Z","caller":"traceutil/trace.go:171","msg":"trace[1584064510] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"163.02318ms","start":"2026-07-06T22:17:03.929726Z","end":"2026-07-06T22:17:04.092749Z","steps":["trace[1584064510] 'process raft request' (duration: 127.940151ms)","trace[1584064510] 'compare' (duration: 34.852523ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:17:04.095597Z","caller":"traceutil/trace.go:171","msg":"trace[465378637] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"165.046013ms","start":"2026-07-06T22:17:03.930526Z","end":"2026-07-06T22:17:04.095572Z","steps":["trace[465378637] 'process raft request' (duration: 164.903779ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:04.345104Z","caller":"traceutil/trace.go:171","msg":"trace[839127569] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"245.663494ms","start":"2026-07-06T22:17:04.099418Z","end":"2026-07-06T22:17:04.345081Z","steps":["trace[839127569] 'process raft request' (duration: 190.174431ms)","trace[839127569] 'compare' (duration: 55.389911ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:17:04.345282Z","caller":"traceutil/trace.go:171","msg":"trace[883811673] linearizableReadLoop","detail":"{readStateIndex:1173; appliedIndex:1172; }","duration":"166.886972ms","start":"2026-07-06T22:17:04.178357Z","end":"2026-07-06T22:17:04.345244Z","steps":["trace[883811673] 'read index received' (duration: 111.107161ms)","trace[883811673] 'applied index is now lower than readState.Index' (duration: 55.774811ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:17:04.345551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.115597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-06T22:17:04.345592Z","caller":"traceutil/trace.go:171","msg":"trace[1611605354] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1120; }","duration":"167.271651ms","start":"2026-07-06T22:17:04.17831Z","end":"2026-07-06T22:17:04.345581Z","steps":["trace[1611605354] 'agreement among raft nodes before linearized reading' (duration: 167.051206ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:04.874735Z","caller":"traceutil/trace.go:171","msg":"trace[1723822511] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"141.769204ms","start":"2026-07-06T22:17:04.732938Z","end":"2026-07-06T22:17:04.874707Z","steps":["trace[1723822511] 'process raft request' (duration: 141.580749ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:04.96459Z","caller":"traceutil/trace.go:171","msg":"trace[222725078] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"197.198055ms","start":"2026-07-06T22:17:04.767357Z","end":"2026-07-06T22:17:04.964556Z","steps":["trace[222725078] 'process raft request' (duration: 165.555006ms)","trace[222725078] 'compare' (duration: 31.395843ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:17:04.965223Z","caller":"traceutil/trace.go:171","msg":"trace[441721372] linearizableReadLoop","detail":"{readStateIndex:1185; appliedIndex:1183; }","duration":"159.534479ms","start":"2026-07-06T22:17:04.805044Z","end":"2026-07-06T22:17:04.964579Z","steps":["trace[441721372] 'read index received' (duration: 69.540563ms)","trace[441721372] 'applied index is now lower than readState.Index' (duration: 89.990486ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:17:04.965225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.165576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-06T22:17:04.965355Z","caller":"traceutil/trace.go:171","msg":"trace[327043187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1133; }","duration":"160.35641ms","start":"2026-07-06T22:17:04.804987Z","end":"2026-07-06T22:17:04.965344Z","steps":["trace[327043187] 'agreement among raft nodes before linearized reading' (duration: 160.044993ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:04.965454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.601106ms","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-07-06T22:17:04.965485Z","caller":"traceutil/trace.go:171","msg":"trace[221006646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1133; }","duration":"150.646787ms","start":"2026-07-06T22:17:04.81483Z","end":"2026-07-06T22:17:04.965476Z","steps":["trace[221006646] 'agreement among raft nodes before linearized reading' (duration: 150.554704ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:05.117332Z","caller":"traceutil/trace.go:171","msg":"trace[616377906] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"147.3307ms","start":"2026-07-06T22:17:04.969962Z","end":"2026-07-06T22:17:05.117293Z","steps":["trace[616377906] 'process raft request' (duration: 134.331309ms)","trace[616377906] 'compare' (duration: 12.769214ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:17:05.117411Z","caller":"traceutil/trace.go:171","msg":"trace[1274540232] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"147.373401ms","start":"2026-07-06T22:17:04.970012Z","end":"2026-07-06T22:17:05.117385Z","steps":["trace[1274540232] 'process raft request' (duration: 147.194305ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:05.117616Z","caller":"traceutil/trace.go:171","msg":"trace[589097437] linearizableReadLoop","detail":"{readStateIndex:1190; appliedIndex:1186; }","duration":"112.316971ms","start":"2026-07-06T22:17:05.005277Z","end":"2026-07-06T22:17:05.117594Z","steps":["trace[589097437] 'read index received' (duration: 13.492173ms)","trace[589097437] 'applied index is now lower than readState.Index' (duration: 98.823228ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:17:05.117652Z","caller":"traceutil/trace.go:171","msg":"trace[1636326310] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"146.487147ms","start":"2026-07-06T22:17:04.971127Z","end":"2026-07-06T22:17:05.117614Z","steps":["trace[1636326310] 'process raft request' (duration: 146.171119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:05.117811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.504378ms","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":"info","ts":"2026-07-06T22:17:05.117869Z","caller":"traceutil/trace.go:171","msg":"trace[1947002015] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1137; }","duration":"112.60299ms","start":"2026-07-06T22:17:05.005254Z","end":"2026-07-06T22:17:05.117857Z","steps":["trace[1947002015] 'agreement among raft nodes before linearized reading' (duration: 112.437985ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:05.254439Z","caller":"traceutil/trace.go:171","msg":"trace[2136106267] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"126.826062ms","start":"2026-07-06T22:17:05.127581Z","end":"2026-07-06T22:17:05.254407Z","steps":["trace[2136106267] 'process raft request' (duration: 126.526575ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:05.254482Z","caller":"traceutil/trace.go:171","msg":"trace[1642132432] linearizableReadLoop","detail":"{readStateIndex:1194; appliedIndex:1191; }","duration":"104.519447ms","start":"2026-07-06T22:17:05.149938Z","end":"2026-07-06T22:17:05.254457Z","steps":["trace[1642132432] 'read index received' (duration: 42.182155ms)","trace[1642132432] 'applied index is now lower than readState.Index' (duration: 62.336132ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:17:05.254624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.671702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:17:05.254675Z","caller":"traceutil/trace.go:171","msg":"trace[1408676711] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1140; }","duration":"104.797665ms","start":"2026-07-06T22:17:05.149864Z","end":"2026-07-06T22:17:05.254662Z","steps":["trace[1408676711] 'agreement among raft nodes before linearized reading' (duration: 104.680422ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:05.254917Z","caller":"traceutil/trace.go:171","msg":"trace[1041882863] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"125.466856ms","start":"2026-07-06T22:17:05.129435Z","end":"2026-07-06T22:17:05.254902Z","steps":["trace[1041882863] 'process raft request' (duration: 124.85971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:10.745131Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938292664276238979,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T22:17:11.246197Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938292664276238979,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T22:17:11.669836Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.604905307s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-06T22:17:11.945713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.594171ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-06T22:17:11.946334Z","caller":"traceutil/trace.go:171","msg":"trace[987943273] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"1.13543853s","start":"2026-07-06T22:17:10.81088Z","end":"2026-07-06T22:17:11.946319Z","steps":["trace[987943273] 'process raft request' (duration: 1.135284816s)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:11.946462Z","caller":"traceutil/trace.go:171","msg":"trace[369053748] linearizableReadLoop","detail":"{readStateIndex:1247; appliedIndex:1244; }","duration":"1.702379294s","start":"2026-07-06T22:17:10.244071Z","end":"2026-07-06T22:17:11.94645Z","steps":["trace[369053748] 'read index received' (duration: 1.426058334s)","trace[369053748] 'applied index is now lower than readState.Index' (duration: 276.31966ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:17:11.946552Z","caller":"traceutil/trace.go:171","msg":"trace[1126317380] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"958.785725ms","start":"2026-07-06T22:17:10.987757Z","end":"2026-07-06T22:17:11.946543Z","steps":["trace[1126317380] 'process raft request' (duration: 958.506408ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:11.946621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:10.987733Z","time spent":"958.842775ms","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T22:17:11.946649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.656781247s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-06T22:17:11.946724Z","caller":"traceutil/trace.go:171","msg":"trace[991970054] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1191; }","duration":"1.656911421s","start":"2026-07-06T22:17:10.289793Z","end":"2026-07-06T22:17:11.946704Z","steps":["trace[991970054] 'agreement among raft nodes before linearized reading' (duration: 1.656782987s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:11.946776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:10.289771Z","time spent":"1.656994264s","remote":"127.0.0.1:58262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-06T22:17:11.946966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.300385058s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:17:11.947007Z","caller":"traceutil/trace.go:171","msg":"trace[1308178060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1191; }","duration":"1.30045812s","start":"2026-07-06T22:17:10.646538Z","end":"2026-07-06T22:17:11.946996Z","steps":["trace[1308178060] 'agreement among raft nodes before linearized reading' (duration: 1.300401328s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:11.947037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:10.646524Z","time spent":"1.300505511s","remote":"127.0.0.1:43822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-06T22:17:11.947044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.702970181s","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-07-06T22:17:11.947079Z","caller":"traceutil/trace.go:171","msg":"trace[1921317388] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1191; }","duration":"1.703034262s","start":"2026-07-06T22:17:10.244031Z","end":"2026-07-06T22:17:11.947066Z","steps":["trace[1921317388] 'agreement among raft nodes before linearized reading' (duration: 1.70299093s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:11.947109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:10.244012Z","time spent":"1.703089583s","remote":"127.0.0.1:58062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-07-06T22:17:11.94722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.489759836s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-06T22:17:11.947257Z","caller":"traceutil/trace.go:171","msg":"trace[2027298079] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1191; }","duration":"1.489808247s","start":"2026-07-06T22:17:10.457438Z","end":"2026-07-06T22:17:11.947247Z","steps":["trace[2027298079] 'agreement among raft nodes before linearized reading' (duration: 1.489725695s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:11.947282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:10.457432Z","time spent":"1.489844048s","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-06T22:17:11.947351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.364976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-06T22:17:11.947342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.336896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-06T22:17:11.947512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.491703917s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-06T22:17:11.947534Z","caller":"traceutil/trace.go:171","msg":"trace[378291963] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1191; }","duration":"811.566783ms","start":"2026-07-06T22:17:11.135956Z","end":"2026-07-06T22:17:11.947523Z","steps":["trace[378291963] 'agreement among raft nodes before linearized reading' (duration: 811.354747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:11.947537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.136439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-06T22:17:11.947571Z","caller":"traceutil/trace.go:171","msg":"trace[2079107749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1191; }","duration":"796.19456ms","start":"2026-07-06T22:17:11.151369Z","end":"2026-07-06T22:17:11.947563Z","steps":["trace[2079107749] 'agreement among raft nodes before linearized reading' (duration: 796.114998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:11.947001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.857089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-06T22:17:11.947604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:11.135936Z","time spent":"811.624685ms","remote":"127.0.0.1:44086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-07-06T22:17:11.947606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:11.151356Z","time spent":"796.242321ms","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-06T22:17:11.947627Z","caller":"traceutil/trace.go:171","msg":"trace[1446872742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1191; }","duration":"908.484595ms","start":"2026-07-06T22:17:11.039133Z","end":"2026-07-06T22:17:11.947618Z","steps":["trace[1446872742] 'agreement among raft nodes before linearized reading' (duration: 907.779457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:11.947657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:11.039087Z","time spent":"908.560018ms","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-06T22:17:11.947544Z","caller":"traceutil/trace.go:171","msg":"trace[944074290] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1191; }","duration":"1.491767869s","start":"2026-07-06T22:17:10.455767Z","end":"2026-07-06T22:17:11.947535Z","steps":["trace[944074290] 'agreement among raft nodes before linearized reading' (duration: 1.491684027s)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:11.947453Z","caller":"traceutil/trace.go:171","msg":"trace[686496473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1191; }","duration":"798.430557ms","start":"2026-07-06T22:17:11.148953Z","end":"2026-07-06T22:17:11.947384Z","steps":["trace[686496473] 'agreement among raft nodes before linearized reading' (duration: 798.161741ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:11.947789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:10.455752Z","time spent":"1.492026795s","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-06T22:17:11.947795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:11.14894Z","time spent":"798.845068ms","remote":"127.0.0.1:44092","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-07-06T22:17:11.947996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:10.810856Z","time spent":"1.135532502s","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:17:14.176705Z","caller":"traceutil/trace.go:171","msg":"trace[1500563349] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"193.582029ms","start":"2026-07-06T22:17:13.983106Z","end":"2026-07-06T22:17:14.176688Z","steps":["trace[1500563349] 'process raft request' (duration: 193.539418ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:14.176828Z","caller":"traceutil/trace.go:171","msg":"trace[983620936] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"193.836465ms","start":"2026-07-06T22:17:13.982985Z","end":"2026-07-06T22:17:14.176822Z","steps":["trace[983620936] 'process raft request' (duration: 192.799618ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:14.176956Z","caller":"traceutil/trace.go:171","msg":"trace[1667760654] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"192.093039ms","start":"2026-07-06T22:17:13.984847Z","end":"2026-07-06T22:17:14.17694Z","steps":["trace[1667760654] 'process raft request' (duration: 192.032778ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:14.177049Z","caller":"traceutil/trace.go:171","msg":"trace[310025402] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"192.410658ms","start":"2026-07-06T22:17:13.984629Z","end":"2026-07-06T22:17:14.17704Z","steps":["trace[310025402] 'process raft request' (duration: 192.035379ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:18.197716Z","caller":"traceutil/trace.go:171","msg":"trace[796290183] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"186.966085ms","start":"2026-07-06T22:17:18.010735Z","end":"2026-07-06T22:17:18.197701Z","steps":["trace[796290183] 'process raft request' (duration: 186.721149ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:28.741376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.889318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-06T22:17:28.741557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.025962ms","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-07-06T22:17:28.741565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.630186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-06T22:17:28.741591Z","caller":"traceutil/trace.go:171","msg":"trace[799400163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1258; }","duration":"345.077784ms","start":"2026-07-06T22:17:28.396502Z","end":"2026-07-06T22:17:28.74158Z","steps":["trace[799400163] 'range keys from in-memory index tree' (duration: 344.893759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:28.741622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:28.396491Z","time spent":"345.124805ms","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-06T22:17:28.741622Z","caller":"traceutil/trace.go:171","msg":"trace[207683343] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1258; }","duration":"375.735499ms","start":"2026-07-06T22:17:28.365873Z","end":"2026-07-06T22:17:28.741608Z","steps":["trace[207683343] 'range keys from in-memory index tree' (duration: 375.502643ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:28.741615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.202126ms","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-07-06T22:17:28.741649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:28.365856Z","time spent":"375.7883ms","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-06T22:17:28.741663Z","caller":"traceutil/trace.go:171","msg":"trace[911157188] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1258; }","duration":"340.290048ms","start":"2026-07-06T22:17:28.401364Z","end":"2026-07-06T22:17:28.741654Z","steps":["trace[911157188] 'range keys from in-memory index tree' (duration: 340.073433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:28.741391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.584571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-07-06T22:17:28.741747Z","caller":"traceutil/trace.go:171","msg":"trace[983516895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1258; }","duration":"339.9613ms","start":"2026-07-06T22:17:28.401463Z","end":"2026-07-06T22:17:28.741424Z","steps":["trace[983516895] 'range keys from in-memory index tree' (duration: 339.750503ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:28.741771Z","caller":"traceutil/trace.go:171","msg":"trace[2045516313] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1258; }","duration":"251.93974ms","start":"2026-07-06T22:17:28.489795Z","end":"2026-07-06T22:17:28.741734Z","steps":["trace[2045516313] 'range keys from in-memory index tree' (duration: 251.445228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:28.741688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:28.401343Z","time spent":"340.337869ms","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-06T22:17:28.742285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:28.401405Z","time spent":"340.36386ms","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-06T22:17:28.860384Z","caller":"traceutil/trace.go:171","msg":"trace[768089998] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"114.896526ms","start":"2026-07-06T22:17:28.745465Z","end":"2026-07-06T22:17:28.860362Z","steps":["trace[768089998] 'process raft request' (duration: 114.765431ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:28.878762Z","caller":"traceutil/trace.go:171","msg":"trace[1953006970] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"127.824514ms","start":"2026-07-06T22:17:28.750914Z","end":"2026-07-06T22:17:28.878739Z","steps":["trace[1953006970] 'process raft request' (duration: 127.776213ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:28.879089Z","caller":"traceutil/trace.go:171","msg":"trace[221849960] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"132.150326ms","start":"2026-07-06T22:17:28.746927Z","end":"2026-07-06T22:17:28.879078Z","steps":["trace[221849960] 'process raft request' (duration: 131.726585ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:28.879093Z","caller":"traceutil/trace.go:171","msg":"trace[1043327288] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"133.077631ms","start":"2026-07-06T22:17:28.745987Z","end":"2026-07-06T22:17:28.879064Z","steps":["trace[1043327288] 'process raft request' (duration: 132.62609ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:28.87936Z","caller":"traceutil/trace.go:171","msg":"trace[1579203158] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"133.455401ms","start":"2026-07-06T22:17:28.745895Z","end":"2026-07-06T22:17:28.879351Z","steps":["trace[1579203158] 'process raft request' (duration: 132.482835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:29.458268Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938292664276239149,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-06T22:17:29.735183Z","caller":"traceutil/trace.go:171","msg":"trace[1492048397] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"785.92164ms","start":"2026-07-06T22:17:28.949238Z","end":"2026-07-06T22:17:29.735159Z","steps":["trace[1492048397] 'process raft request' (duration: 785.765256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:29.735482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:28.949217Z","time spent":"786.038352ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:17:29.894602Z","caller":"traceutil/trace.go:171","msg":"trace[1443117178] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1323; }","duration":"936.406438ms","start":"2026-07-06T22:17:28.958162Z","end":"2026-07-06T22:17:29.894569Z","steps":["trace[1443117178] 'read index received' (duration: 776.801211ms)","trace[1443117178] 'applied index is now lower than readState.Index' (duration: 159.604087ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:17:29.894871Z","caller":"traceutil/trace.go:171","msg":"trace[1887395322] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"745.361325ms","start":"2026-07-06T22:17:29.149486Z","end":"2026-07-06T22:17:29.894847Z","steps":["trace[1887395322] 'process raft request' (duration: 744.141563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:29.895018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.794008ms","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-07-06T22:17:29.895093Z","caller":"traceutil/trace.go:171","msg":"trace[1249905663] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1266; }","duration":"936.942013ms","start":"2026-07-06T22:17:28.958135Z","end":"2026-07-06T22:17:29.895077Z","steps":["trace[1249905663] 'agreement among raft nodes before linearized reading' (duration: 936.696786ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:29.895146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:28.958124Z","time spent":"937.007614ms","remote":"127.0.0.1:46774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":114,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-07-06T22:17:29.89521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:29.14946Z","time spent":"745.539929ms","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:17:29.939873Z","caller":"traceutil/trace.go:171","msg":"trace[561403097] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"193.155588ms","start":"2026-07-06T22:17:29.746694Z","end":"2026-07-06T22:17:29.93985Z","steps":["trace[561403097] 'process raft request' (duration: 193.091345ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:29.940167Z","caller":"traceutil/trace.go:171","msg":"trace[572814011] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"198.074087ms","start":"2026-07-06T22:17:29.742086Z","end":"2026-07-06T22:17:29.94016Z","steps":["trace[572814011] 'process raft request' (duration: 197.667166ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:29.94056Z","caller":"traceutil/trace.go:171","msg":"trace[39706629] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"198.894038ms","start":"2026-07-06T22:17:29.741625Z","end":"2026-07-06T22:17:29.940519Z","steps":["trace[39706629] 'process raft request' (duration: 197.990125ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:31.082773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.407259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:17:31.082876Z","caller":"traceutil/trace.go:171","msg":"trace[1313604906] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1271; }","duration":"577.558673ms","start":"2026-07-06T22:17:30.505297Z","end":"2026-07-06T22:17:31.082855Z","steps":["trace[1313604906] 'count revisions from in-memory index tree' (duration: 577.332588ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:31.082927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:30.505279Z","time spent":"577.634385ms","remote":"127.0.0.1:46752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-06T22:17:31.083207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.476749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-06T22:17:31.083284Z","caller":"traceutil/trace.go:171","msg":"trace[1656497936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1271; }","duration":"219.560801ms","start":"2026-07-06T22:17:30.863705Z","end":"2026-07-06T22:17:31.083266Z","steps":["trace[1656497936] 'range keys from in-memory index tree' (duration: 219.333784ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:31.083302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.164392ms","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-07-06T22:17:31.083345Z","caller":"traceutil/trace.go:171","msg":"trace[1879423478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1271; }","duration":"200.210683ms","start":"2026-07-06T22:17:30.883122Z","end":"2026-07-06T22:17:31.083332Z","steps":["trace[1879423478] 'range keys from in-memory index tree' (duration: 200.10888ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:31.083207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.711486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-06T22:17:31.083518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.076315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-06T22:17:31.083556Z","caller":"traceutil/trace.go:171","msg":"trace[1716609089] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1271; }","duration":"201.085116ms","start":"2026-07-06T22:17:30.882461Z","end":"2026-07-06T22:17:31.083546Z","steps":["trace[1716609089] 'range keys from in-memory index tree' (duration: 200.615715ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:31.083572Z","caller":"traceutil/trace.go:171","msg":"trace[200090864] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1271; }","duration":"201.149298ms","start":"2026-07-06T22:17:30.882404Z","end":"2026-07-06T22:17:31.083553Z","steps":["trace[200090864] 'range keys from in-memory index tree' (duration: 200.930372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:31.083586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.673657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:17:31.083628Z","caller":"traceutil/trace.go:171","msg":"trace[482645160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1271; }","duration":"433.749489ms","start":"2026-07-06T22:17:30.649866Z","end":"2026-07-06T22:17:31.083616Z","steps":["trace[482645160] 'range keys from in-memory index tree' (duration: 433.604676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:31.083666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:30.649849Z","time spent":"433.810001ms","remote":"127.0.0.1:43832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-06T22:17:31.614796Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938292664276239166,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-06T22:17:31.707884Z","caller":"traceutil/trace.go:171","msg":"trace[1520807844] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"620.696135ms","start":"2026-07-06T22:17:31.08715Z","end":"2026-07-06T22:17:31.707846Z","steps":["trace[1520807844] 'process raft request' (duration: 620.49328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:31.708113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:31.087129Z","time spent":"620.839259ms","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:17:31.727409Z","caller":"traceutil/trace.go:171","msg":"trace[1263473230] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"638.958045ms","start":"2026-07-06T22:17:31.088417Z","end":"2026-07-06T22:17:31.727375Z","steps":["trace[1263473230] 'process raft request' (duration: 638.740348ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:31.727504Z","caller":"traceutil/trace.go:171","msg":"trace[1566976023] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"638.778929ms","start":"2026-07-06T22:17:31.088704Z","end":"2026-07-06T22:17:31.727482Z","steps":["trace[1566976023] 'process raft request' (duration: 638.557624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:31.727594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:31.088403Z","time spent":"639.078097ms","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:17:31.727626Z","caller":"traceutil/trace.go:171","msg":"trace[594535642] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1331; }","duration":"613.601539ms","start":"2026-07-06T22:17:31.114012Z","end":"2026-07-06T22:17:31.727613Z","steps":["trace[594535642] 'read index received' (duration: 593.538262ms)","trace[594535642] 'applied index is now lower than readState.Index' (duration: 20.062617ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:17:31.727656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:31.08869Z","time spent":"638.894833ms","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:17:31.727756Z","caller":"traceutil/trace.go:171","msg":"trace[639425748] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"638.491252ms","start":"2026-07-06T22:17:31.089255Z","end":"2026-07-06T22:17:31.727746Z","steps":["trace[639425748] 'process raft request' (duration: 638.030771ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:31.727817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.788463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:17:31.727899Z","caller":"traceutil/trace.go:171","msg":"trace[529203950] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1275; }","duration":"613.914807ms","start":"2026-07-06T22:17:31.113968Z","end":"2026-07-06T22:17:31.727883Z","steps":["trace[529203950] 'agreement among raft nodes before linearized reading' (duration: 613.754642ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:31.727834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:31.089249Z","time spent":"638.526383ms","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T22:17:31.727943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:31.113952Z","time spent":"613.977898ms","remote":"127.0.0.1:38526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-06T22:17:31.728337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.120734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:17:31.728375Z","caller":"traceutil/trace.go:171","msg":"trace[798658384] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1275; }","duration":"462.209736ms","start":"2026-07-06T22:17:31.266155Z","end":"2026-07-06T22:17:31.728364Z","steps":["trace[798658384] 'agreement among raft nodes before linearized reading' (duration: 462.146395ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:31.728411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:31.266135Z","time spent":"462.264548ms","remote":"127.0.0.1:60670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-07-06T22:17:32.460603Z","caller":"traceutil/trace.go:171","msg":"trace[1812019982] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"278.024894ms","start":"2026-07-06T22:17:32.182559Z","end":"2026-07-06T22:17:32.460584Z","steps":["trace[1812019982] 'process raft request' (duration: 277.85317ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:32.908023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.673322ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T22:17:32.908127Z","caller":"traceutil/trace.go:171","msg":"trace[2091722379] linearizableReadLoop","detail":"{readStateIndex:1337; appliedIndex:1335; }","duration":"535.471799ms","start":"2026-07-06T22:17:32.372639Z","end":"2026-07-06T22:17:32.908111Z","steps":["trace[2091722379] 'read index received' (duration: 87.848886ms)","trace[2091722379] 'applied index is now lower than readState.Index' (duration: 447.621623ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:17:32.908213Z","caller":"traceutil/trace.go:171","msg":"trace[857836542] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"566.663986ms","start":"2026-07-06T22:17:32.341535Z","end":"2026-07-06T22:17:32.908199Z","steps":["trace[857836542] 'process raft request' (duration: 327.738168ms)","trace[857836542] 'compare' (duration: 238.311173ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:17:32.908268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:32.34151Z","time spent":"566.727448ms","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T22:17:32.908466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.831419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-06T22:17:32.908502Z","caller":"traceutil/trace.go:171","msg":"trace[1469571685] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1277; }","duration":"535.900671ms","start":"2026-07-06T22:17:32.372592Z","end":"2026-07-06T22:17:32.908492Z","steps":["trace[1469571685] 'agreement among raft nodes before linearized reading' (duration: 535.770907ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:32.908534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:32.372556Z","time spent":"535.971683ms","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-06T22:17:32.908635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.130325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:17:32.908664Z","caller":"traceutil/trace.go:171","msg":"trace[1392401477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1277; }","duration":"260.193567ms","start":"2026-07-06T22:17:32.648462Z","end":"2026-07-06T22:17:32.908656Z","steps":["trace[1392401477] 'agreement among raft nodes before linearized reading' (duration: 260.154196ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:33.19495Z","caller":"traceutil/trace.go:171","msg":"trace[1250093008] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"281.29119ms","start":"2026-07-06T22:17:32.913618Z","end":"2026-07-06T22:17:33.19491Z","steps":["trace[1250093008] 'process raft request' (duration: 281.090755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:33.668593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.659251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:17:33.668677Z","caller":"traceutil/trace.go:171","msg":"trace[408546323] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1279; }","duration":"241.790505ms","start":"2026-07-06T22:17:33.426868Z","end":"2026-07-06T22:17:33.668658Z","steps":["trace[408546323] 'count revisions from in-memory index tree' (duration: 241.597689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:33.668912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.360018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-06T22:17:33.6689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.808576ms","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-07-06T22:17:33.668969Z","caller":"traceutil/trace.go:171","msg":"trace[1833255137] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1279; }","duration":"242.470541ms","start":"2026-07-06T22:17:33.426485Z","end":"2026-07-06T22:17:33.668955Z","steps":["trace[1833255137] 'count revisions from in-memory index tree' (duration: 242.283196ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:33.66901Z","caller":"traceutil/trace.go:171","msg":"trace[1706828640] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1279; }","duration":"347.910338ms","start":"2026-07-06T22:17:33.32108Z","end":"2026-07-06T22:17:33.66899Z","steps":["trace[1706828640] 'range keys from in-memory index tree' (duration: 347.797105ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:34.475254Z","caller":"traceutil/trace.go:171","msg":"trace[1217306958] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"704.193545ms","start":"2026-07-06T22:17:33.771041Z","end":"2026-07-06T22:17:34.475234Z","steps":["trace[1217306958] 'process raft request' (duration: 704.154915ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:34.475392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:33.771032Z","time spent":"704.291418ms","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:17:34.475387Z","caller":"traceutil/trace.go:171","msg":"trace[1853879763] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"704.486072ms","start":"2026-07-06T22:17:33.770868Z","end":"2026-07-06T22:17:34.475354Z","steps":["trace[1853879763] 'process raft request' (duration: 704.183114ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:34.475534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:33.770847Z","time spent":"704.606176ms","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T22:17:34.772472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.716302ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T22:17:34.772591Z","caller":"traceutil/trace.go:171","msg":"trace[772136786] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1343; }","duration":"123.755306ms","start":"2026-07-06T22:17:34.648816Z","end":"2026-07-06T22:17:34.772572Z","steps":["trace[772136786] 'read index received' (duration: 33.221µs)","trace[772136786] 'applied index is now lower than readState.Index' (duration: 123.720955ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:17:34.772761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.87121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:17:34.772818Z","caller":"traceutil/trace.go:171","msg":"trace[1934838759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1284; }","duration":"124.016633ms","start":"2026-07-06T22:17:34.648785Z","end":"2026-07-06T22:17:34.772801Z","steps":["trace[1934838759] 'agreement among raft nodes before linearized reading' (duration: 123.839629ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:34.774714Z","caller":"traceutil/trace.go:171","msg":"trace[1367882152] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"308.456142ms","start":"2026-07-06T22:17:34.466221Z","end":"2026-07-06T22:17:34.774677Z","steps":["trace[1367882152] 'process raft request' (duration: 152.45052ms)","trace[1367882152] 'compare' (duration: 153.547158ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:17:34.774995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:34.4662Z","time spent":"308.659458ms","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:17:35.119906Z","caller":"traceutil/trace.go:171","msg":"trace[1519574698] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"102.705795ms","start":"2026-07-06T22:17:35.017175Z","end":"2026-07-06T22:17:35.11988Z","steps":["trace[1519574698] 'process raft request' (duration: 102.517529ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:36.041342Z","caller":"traceutil/trace.go:171","msg":"trace[1649840020] linearizableReadLoop","detail":"{readStateIndex:1354; appliedIndex:1353; }","duration":"222.025465ms","start":"2026-07-06T22:17:35.819296Z","end":"2026-07-06T22:17:36.041322Z","steps":["trace[1649840020] 'read index received' (duration: 221.751927ms)","trace[1649840020] 'applied index is now lower than readState.Index' (duration: 272.868µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:17:36.041415Z","caller":"traceutil/trace.go:171","msg":"trace[428526310] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"268.107654ms","start":"2026-07-06T22:17:35.773294Z","end":"2026-07-06T22:17:36.041402Z","steps":["trace[428526310] 'process raft request' (duration: 267.861347ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:36.041583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.276961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-06T22:17:36.041616Z","caller":"traceutil/trace.go:171","msg":"trace[1468431507] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1293; }","duration":"222.357773ms","start":"2026-07-06T22:17:35.81925Z","end":"2026-07-06T22:17:36.041608Z","steps":["trace[1468431507] 'agreement among raft nodes before linearized reading' (duration: 222.170188ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:39.323789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.879669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-06T22:17:39.323886Z","caller":"traceutil/trace.go:171","msg":"trace[913642967] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1317; }","duration":"103.041922ms","start":"2026-07-06T22:17:39.220827Z","end":"2026-07-06T22:17:39.323869Z","steps":["trace[913642967] 'range keys from in-memory index tree' (duration: 102.685154ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:39.557452Z","caller":"traceutil/trace.go:171","msg":"trace[1772077522] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"229.407399ms","start":"2026-07-06T22:17:39.328025Z","end":"2026-07-06T22:17:39.557432Z","steps":["trace[1772077522] 'process raft request' (duration: 229.286176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:40.626899Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938292664276239261,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T22:17:40.643143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.2239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-06T22:17:40.643199Z","caller":"traceutil/trace.go:171","msg":"trace[1118205260] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1318; }","duration":"589.329522ms","start":"2026-07-06T22:17:40.053855Z","end":"2026-07-06T22:17:40.643184Z","steps":["trace[1118205260] 'range keys from in-memory index tree' (duration: 589.050916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:40.643233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:40.053836Z","time spent":"589.388544ms","remote":"127.0.0.1:44092","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-07-06T22:17:40.643351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.339023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-06T22:17:40.64339Z","caller":"traceutil/trace.go:171","msg":"trace[563589582] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1318; }","duration":"549.418085ms","start":"2026-07-06T22:17:40.093956Z","end":"2026-07-06T22:17:40.643374Z","steps":["trace[563589582] 'range keys from in-memory index tree' (duration: 549.202399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:40.643347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.002162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:17:40.643449Z","caller":"traceutil/trace.go:171","msg":"trace[1393745717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1318; }","duration":"996.157966ms","start":"2026-07-06T22:17:39.647266Z","end":"2026-07-06T22:17:40.643424Z","steps":["trace[1393745717] 'range keys from in-memory index tree' (duration: 995.915159ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:40.64355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:39.647248Z","time spent":"996.280439ms","remote":"127.0.0.1:43832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-06T22:17:40.643412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:40.093926Z","time spent":"549.480207ms","remote":"127.0.0.1:44092","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-07-06T22:17:40.648729Z","caller":"traceutil/trace.go:171","msg":"trace[31376206] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1379; }","duration":"522.907999ms","start":"2026-07-06T22:17:40.125809Z","end":"2026-07-06T22:17:40.648717Z","steps":["trace[31376206] 'read index received' (duration: 515.835534ms)","trace[31376206] 'applied index is now lower than readState.Index' (duration: 7.071895ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:17:40.648991Z","caller":"traceutil/trace.go:171","msg":"trace[738382880] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"463.675475ms","start":"2026-07-06T22:17:40.1853Z","end":"2026-07-06T22:17:40.648975Z","steps":["trace[738382880] 'process raft request' (duration: 463.331646ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:40.649101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:40.185275Z","time spent":"463.747377ms","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T22:17:40.649304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.500675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-07-06T22:17:40.649343Z","caller":"traceutil/trace.go:171","msg":"trace[936912512] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1319; }","duration":"523.579517ms","start":"2026-07-06T22:17:40.125755Z","end":"2026-07-06T22:17:40.649335Z","steps":["trace[936912512] 'agreement among raft nodes before linearized reading' (duration: 523.482194ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:40.649369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:40.125735Z","time spent":"523.627868ms","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-06T22:17:40.650731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.829441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:17:40.650775Z","caller":"traceutil/trace.go:171","msg":"trace[1750732581] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1320; }","duration":"213.897672ms","start":"2026-07-06T22:17:40.436866Z","end":"2026-07-06T22:17:40.650763Z","steps":["trace[1750732581] 'agreement among raft nodes before linearized reading' (duration: 213.847141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:40.650789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.883872ms","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-07-06T22:17:40.650814Z","caller":"traceutil/trace.go:171","msg":"trace[1054239614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1320; }","duration":"152.946843ms","start":"2026-07-06T22:17:40.49786Z","end":"2026-07-06T22:17:40.650807Z","steps":["trace[1054239614] 'agreement among raft nodes before linearized reading' (duration: 152.896262ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:40.650739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.027001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-06T22:17:40.650885Z","caller":"traceutil/trace.go:171","msg":"trace[1196095583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1320; }","duration":"154.222876ms","start":"2026-07-06T22:17:40.496653Z","end":"2026-07-06T22:17:40.650876Z","steps":["trace[1196095583] 'agreement among raft nodes before linearized reading' (duration: 154.022041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:40.650636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.430954ms","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-07-06T22:17:40.651201Z","caller":"traceutil/trace.go:171","msg":"trace[604978755] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1320; }","duration":"380.04463ms","start":"2026-07-06T22:17:40.271143Z","end":"2026-07-06T22:17:40.651187Z","steps":["trace[604978755] 'agreement among raft nodes before linearized reading' (duration: 379.419883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:40.651253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:40.271125Z","time spent":"380.115373ms","remote":"127.0.0.1:58170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-07-06T22:17:42.219615Z","caller":"traceutil/trace.go:171","msg":"trace[1936892615] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"242.810731ms","start":"2026-07-06T22:17:41.976777Z","end":"2026-07-06T22:17:42.219587Z","steps":["trace[1936892615] 'process raft request' (duration: 242.620825ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:52.405441Z","caller":"traceutil/trace.go:171","msg":"trace[811310046] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"169.255751ms","start":"2026-07-06T22:17:52.235945Z","end":"2026-07-06T22:17:52.405201Z","steps":["trace[811310046] 'process raft request' (duration: 169.105966ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:53.637196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.261094ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T22:17:53.637305Z","caller":"traceutil/trace.go:171","msg":"trace[1050496464] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"183.019552ms","start":"2026-07-06T22:17:53.454263Z","end":"2026-07-06T22:17:53.637283Z","steps":["trace[1050496464] 'process raft request' (duration: 46.591463ms)","trace[1050496464] 'compare' (duration: 136.131141ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:18:45.353245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.114426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-06T22:18:45.353279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.153337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-06T22:18:45.353331Z","caller":"traceutil/trace.go:171","msg":"trace[1997227342] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1633; }","duration":"224.246491ms","start":"2026-07-06T22:18:45.129068Z","end":"2026-07-06T22:18:45.353314Z","steps":["trace[1997227342] 'count revisions from in-memory index tree' (duration: 223.995203ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:18:45.353347Z","caller":"traceutil/trace.go:171","msg":"trace[1811021948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1633; }","duration":"224.22256ms","start":"2026-07-06T22:18:45.129105Z","end":"2026-07-06T22:18:45.353328Z","steps":["trace[1811021948] 'range keys from in-memory index tree' (duration: 223.977404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:18:45.353366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.303142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-06T22:18:45.353403Z","caller":"traceutil/trace.go:171","msg":"trace[315147007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1633; }","duration":"226.376083ms","start":"2026-07-06T22:18:45.127017Z","end":"2026-07-06T22:18:45.353393Z","steps":["trace[315147007] 'range keys from in-memory index tree' (duration: 226.157128ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:18:45.353445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.007034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-06T22:18:45.353543Z","caller":"traceutil/trace.go:171","msg":"trace[1297223180] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1633; }","duration":"222.140367ms","start":"2026-07-06T22:18:45.131377Z","end":"2026-07-06T22:18:45.353518Z","steps":["trace[1297223180] 'range keys from in-memory index tree' (duration: 221.820608ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:18:45.361225Z","caller":"traceutil/trace.go:171","msg":"trace[1783932755] transaction","detail":"{read_only:false; response_revision:1634; number_of_response:1; }","duration":"221.531992ms","start":"2026-07-06T22:18:45.139675Z","end":"2026-07-06T22:18:45.361207Z","steps":["trace[1783932755] 'process raft request' (duration: 221.42342ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:03.250189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.054783ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T22:19:03.25053Z","caller":"traceutil/trace.go:171","msg":"trace[1558325927] transaction","detail":"{read_only:false; response_revision:1714; number_of_response:1; }","duration":"305.357665ms","start":"2026-07-06T22:19:02.945146Z","end":"2026-07-06T22:19:03.250504Z","steps":["trace[1558325927] 'process raft request' (duration: 191.894331ms)","trace[1558325927] 'compare' (duration: 112.94748ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:19:03.250621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:19:02.945104Z","time spent":"305.471748ms","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:19:03.455754Z","caller":"traceutil/trace.go:171","msg":"trace[394332095] transaction","detail":"{read_only:false; response_revision:1715; number_of_response:1; }","duration":"201.569615ms","start":"2026-07-06T22:19:03.254164Z","end":"2026-07-06T22:19:03.455733Z","steps":["trace[394332095] 'process raft request' (duration: 197.495728ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:19:03.638585Z","caller":"traceutil/trace.go:171","msg":"trace[1781522376] transaction","detail":"{read_only:false; response_revision:1718; number_of_response:1; }","duration":"123.439123ms","start":"2026-07-06T22:19:03.515123Z","end":"2026-07-06T22:19:03.638562Z","steps":["trace[1781522376] 'process raft request' (duration: 121.04177ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:19:03.733435Z","caller":"traceutil/trace.go:171","msg":"trace[1494247340] transaction","detail":"{read_only:false; response_revision:1719; number_of_response:1; }","duration":"217.234532ms","start":"2026-07-06T22:19:03.516164Z","end":"2026-07-06T22:19:03.733398Z","steps":["trace[1494247340] 'process raft request' (duration: 217.005577ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:19:06.09967Z","caller":"traceutil/trace.go:171","msg":"trace[1035181076] transaction","detail":"{read_only:false; response_revision:1730; number_of_response:1; }","duration":"128.619909ms","start":"2026-07-06T22:19:05.97102Z","end":"2026-07-06T22:19:06.09964Z","steps":["trace[1035181076] 'process raft request' (duration: 128.419224ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:19:07.845142Z","caller":"traceutil/trace.go:171","msg":"trace[2124990350] transaction","detail":"{read_only:false; response_revision:1735; number_of_response:1; }","duration":"247.988306ms","start":"2026-07-06T22:19:07.59713Z","end":"2026-07-06T22:19:07.845119Z","steps":["trace[2124990350] 'process raft request' (duration: 231.424884ms)","trace[2124990350] 'compare' (duration: 16.383217ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:19:07.845253Z","caller":"traceutil/trace.go:171","msg":"trace[579106365] linearizableReadLoop","detail":"{readStateIndex:1815; appliedIndex:1813; }","duration":"196.972134ms","start":"2026-07-06T22:19:07.648255Z","end":"2026-07-06T22:19:07.845228Z","steps":["trace[579106365] 'read index received' (duration: 180.240697ms)","trace[579106365] 'applied index is now lower than readState.Index' (duration: 16.730397ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:19:07.845367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.110137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:19:07.845393Z","caller":"traceutil/trace.go:171","msg":"trace[1582414403] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1736; }","duration":"197.21461ms","start":"2026-07-06T22:19:07.648171Z","end":"2026-07-06T22:19:07.845386Z","steps":["trace[1582414403] 'agreement among raft nodes before linearized reading' (duration: 197.155049ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:07.84542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.888883ms","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-07-06T22:19:07.845449Z","caller":"traceutil/trace.go:171","msg":"trace[38878353] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1736; }","duration":"183.974065ms","start":"2026-07-06T22:19:07.661468Z","end":"2026-07-06T22:19:07.845442Z","steps":["trace[38878353] 'agreement among raft nodes before linearized reading' (duration: 183.864332ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:19:07.845482Z","caller":"traceutil/trace.go:171","msg":"trace[2107641847] transaction","detail":"{read_only:false; response_revision:1736; number_of_response:1; }","duration":"247.626216ms","start":"2026-07-06T22:19:07.59785Z","end":"2026-07-06T22:19:07.845476Z","steps":["trace[2107641847] 'process raft request' (duration: 247.223105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:20.7651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.66804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:19:20.765194Z","caller":"traceutil/trace.go:171","msg":"trace[1855147006] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1794; }","duration":"116.834513ms","start":"2026-07-06T22:19:20.648343Z","end":"2026-07-06T22:19:20.765177Z","steps":["trace[1855147006] 'range keys from in-memory index tree' (duration: 116.602007ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:19:27.652562Z","caller":"traceutil/trace.go:171","msg":"trace[749558709] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"100.539666ms","start":"2026-07-06T22:19:27.551999Z","end":"2026-07-06T22:19:27.652539Z","steps":["trace[749558709] 'process raft request' (duration: 100.162356ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:28.045846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.055725ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T22:19:28.045968Z","caller":"traceutil/trace.go:171","msg":"trace[1989025563] linearizableReadLoop","detail":"{readStateIndex:1909; appliedIndex:1908; }","duration":"167.064496ms","start":"2026-07-06T22:19:27.878886Z","end":"2026-07-06T22:19:28.04595Z","steps":["trace[1989025563] 'read index received' (duration: 45.727654ms)","trace[1989025563] 'applied index is now lower than readState.Index' (duration: 121.335452ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:19:28.046017Z","caller":"traceutil/trace.go:171","msg":"trace[1456277253] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"229.850597ms","start":"2026-07-06T22:19:27.816126Z","end":"2026-07-06T22:19:28.045976Z","steps":["trace[1456277253] 'process raft request' (duration: 108.584916ms)","trace[1456277253] 'compare' (duration: 120.907001ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:19:28.046776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.893717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-06T22:19:28.046879Z","caller":"traceutil/trace.go:171","msg":"trace[475173281] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1826; }","duration":"168.034201ms","start":"2026-07-06T22:19:27.878822Z","end":"2026-07-06T22:19:28.046856Z","steps":["trace[475173281] 'agreement among raft nodes before linearized reading' (duration: 167.216019ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:19:40.23215Z","caller":"traceutil/trace.go:171","msg":"trace[319089018] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"111.967757ms","start":"2026-07-06T22:19:40.120158Z","end":"2026-07-06T22:19:40.232126Z","steps":["trace[319089018] 'process raft request' (duration: 111.083384ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:20:06.418577Z","caller":"traceutil/trace.go:171","msg":"trace[2127443556] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"109.003087ms","start":"2026-07-06T22:20:06.309555Z","end":"2026-07-06T22:20:06.418558Z","steps":["trace[2127443556] 'process raft request' (duration: 108.847623ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:20:06.589923Z","caller":"traceutil/trace.go:171","msg":"trace[1173654314] linearizableReadLoop","detail":"{readStateIndex:2096; appliedIndex:2095; }","duration":"114.187484ms","start":"2026-07-06T22:20:06.475711Z","end":"2026-07-06T22:20:06.589898Z","steps":["trace[1173654314] 'read index received' (duration: 49.082621ms)","trace[1173654314] 'applied index is now lower than readState.Index' (duration: 65.103893ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:20:06.590008Z","caller":"traceutil/trace.go:171","msg":"trace[1951909603] transaction","detail":"{read_only:false; response_revision:2005; number_of_response:1; }","duration":"123.086568ms","start":"2026-07-06T22:20:06.466883Z","end":"2026-07-06T22:20:06.58997Z","steps":["trace[1951909603] 'process raft request' (duration: 57.983275ms)","trace[1951909603] 'compare' (duration: 64.926349ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:20:06.590143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.398519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-06T22:20:06.590255Z","caller":"traceutil/trace.go:171","msg":"trace[968725716] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:2005; }","duration":"114.552075ms","start":"2026-07-06T22:20:06.475678Z","end":"2026-07-06T22:20:06.59023Z","steps":["trace[968725716] 'agreement among raft nodes before linearized reading' (duration: 114.353169ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:20:53.446317Z","caller":"traceutil/trace.go:171","msg":"trace[409409549] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"118.741248ms","start":"2026-07-06T22:20:53.327551Z","end":"2026-07-06T22:20:53.446292Z","steps":["trace[409409549] 'process raft request' (duration: 118.525973ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:20:53.456256Z","caller":"traceutil/trace.go:171","msg":"trace[1035418775] transaction","detail":"{read_only:false; response_revision:2219; number_of_response:1; }","duration":"127.597388ms","start":"2026-07-06T22:20:53.328633Z","end":"2026-07-06T22:20:53.45623Z","steps":["trace[1035418775] 'process raft request' (duration: 127.018343ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:20:56.243079Z","caller":"traceutil/trace.go:171","msg":"trace[734099073] transaction","detail":"{read_only:false; response_revision:2230; number_of_response:1; }","duration":"117.006882ms","start":"2026-07-06T22:20:56.126045Z","end":"2026-07-06T22:20:56.243052Z","steps":["trace[734099073] 'process raft request' (duration: 116.856108ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:20:56.641456Z","caller":"traceutil/trace.go:171","msg":"trace[1392310507] transaction","detail":"{read_only:false; response_revision:2232; number_of_response:1; }","duration":"131.07595ms","start":"2026-07-06T22:20:56.510354Z","end":"2026-07-06T22:20:56.64143Z","steps":["trace[1392310507] 'process raft request' (duration: 130.936397ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:21:56.990932Z","caller":"traceutil/trace.go:171","msg":"trace[978397309] transaction","detail":"{read_only:false; response_revision:2544; number_of_response:1; }","duration":"103.583505ms","start":"2026-07-06T22:21:56.887326Z","end":"2026-07-06T22:21:56.99091Z","steps":["trace[978397309] 'process raft request' (duration: 103.405479ms)"],"step_count":1}