{"level":"warn","ts":"2026-04-07T17:46:21.706913Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T17:46:21.707019Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.63:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.63:2380","--initial-cluster=instance=https://199.19.213.63:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.63:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.63:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-07T17:46:21.707091Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T17:46:21.707102Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.63:2380"]} {"level":"info","ts":"2026-04-07T17:46:21.70712Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T17:46:21.707761Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.63:2379"]} {"level":"info","ts":"2026-04-07T17:46:21.70783Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.63:2380"],"listen-peer-urls":["https://199.19.213.63:2380"],"advertise-client-urls":["https://199.19.213.63:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.63:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.63:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-07T17:46:21.753864Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"45.819297ms"} {"level":"info","ts":"2026-04-07T17:46:21.911543Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d75c480e593e7aed","cluster-id":"24fce67309ccbdc"} {"level":"info","ts":"2026-04-07T17:46:21.911672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T17:46:21.911729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed became follower at term 0"} {"level":"info","ts":"2026-04-07T17:46:21.911749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d75c480e593e7aed [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T17:46:21.911772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed became follower at term 1"} {"level":"info","ts":"2026-04-07T17:46:21.911826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed switched to configuration voters=(15518357642475895533)"} {"level":"warn","ts":"2026-04-07T17:46:22.082183Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T17:46:22.088077Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T17:46:22.112136Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-07T17:46:22.115646Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d75c480e593e7aed","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T17:46:22.115903Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d75c480e593e7aed","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T17:46:22.125491Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T17:46:22.125579Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T17:46:22.125604Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T17:46:22.131915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed switched to configuration voters=(15518357642475895533)"} {"level":"info","ts":"2026-04-07T17:46:22.132067Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"24fce67309ccbdc","local-member-id":"d75c480e593e7aed","added-peer-id":"d75c480e593e7aed","added-peer-peer-urls":["https://199.19.213.63:2380"]} {"level":"info","ts":"2026-04-07T17:46:22.133017Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T17:46:22.133234Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d75c480e593e7aed","initial-advertise-peer-urls":["https://199.19.213.63:2380"],"listen-peer-urls":["https://199.19.213.63:2380"],"advertise-client-urls":["https://199.19.213.63:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.63:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T17:46:22.133284Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T17:46:22.133344Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.63:2380"} {"level":"info","ts":"2026-04-07T17:46:22.133389Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.63:2380"} {"level":"info","ts":"2026-04-07T17:46:22.915648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T17:46:22.915749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T17:46:22.915791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed received MsgPreVoteResp from d75c480e593e7aed at term 1"} {"level":"info","ts":"2026-04-07T17:46:22.915812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed became candidate at term 2"} {"level":"info","ts":"2026-04-07T17:46:22.915823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed received MsgVoteResp from d75c480e593e7aed at term 2"} {"level":"info","ts":"2026-04-07T17:46:22.915839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed became leader at term 2"} {"level":"info","ts":"2026-04-07T17:46:22.915853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d75c480e593e7aed elected leader d75c480e593e7aed at term 2"} {"level":"info","ts":"2026-04-07T17:46:22.919387Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T17:46:22.927556Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d75c480e593e7aed","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.63:2379]}","request-path":"/0/members/d75c480e593e7aed/attributes","cluster-id":"24fce67309ccbdc","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T17:46:22.927587Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T17:46:22.92765Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T17:46:22.928091Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T17:46:22.928181Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T17:46:22.93138Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T17:46:22.931545Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.63:2379"} {"level":"info","ts":"2026-04-07T17:46:22.933246Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"24fce67309ccbdc","local-member-id":"d75c480e593e7aed","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T17:46:22.933522Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T17:46:22.933649Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T17:46:24.807576Z","caller":"traceutil/trace.go:171","msg":"trace[630354874] transaction","detail":"{read_only:false; response_revision:45; number_of_response:1; }","duration":"141.518716ms","start":"2026-04-07T17:46:24.666023Z","end":"2026-04-07T17:46:24.807542Z","steps":["trace[630354874] 'process raft request' (duration: 98.922005ms)","trace[630354874] 'compare' (duration: 42.395687ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:46:24.80783Z","caller":"traceutil/trace.go:171","msg":"trace[1166153629] transaction","detail":"{read_only:false; response_revision:46; number_of_response:1; }","duration":"115.411065ms","start":"2026-04-07T17:46:24.692342Z","end":"2026-04-07T17:46:24.807753Z","steps":["trace[1166153629] 'process raft request' (duration: 115.152351ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:46:25.037501Z","caller":"traceutil/trace.go:171","msg":"trace[1027657461] transaction","detail":"{read_only:false; response_revision:48; number_of_response:1; }","duration":"225.316378ms","start":"2026-04-07T17:46:24.811971Z","end":"2026-04-07T17:46:25.037288Z","steps":["trace[1027657461] 'process raft request' (duration: 178.625104ms)","trace[1027657461] 'compare' (duration: 46.592032ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:46:25.038801Z","caller":"traceutil/trace.go:171","msg":"trace[552562681] transaction","detail":"{read_only:false; response_revision:49; number_of_response:1; }","duration":"140.134722ms","start":"2026-04-07T17:46:24.898615Z","end":"2026-04-07T17:46:25.03875Z","steps":["trace[552562681] 'process raft request' (duration: 139.931459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:46:29.358927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.597836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-07T17:46:29.359058Z","caller":"traceutil/trace.go:171","msg":"trace[1836538295] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:279; }","duration":"272.808949ms","start":"2026-04-07T17:46:29.086226Z","end":"2026-04-07T17:46:29.359035Z","steps":["trace[1836538295] 'range keys from in-memory index tree' (duration: 272.504294ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:46:32.534587Z","caller":"traceutil/trace.go:171","msg":"trace[1808669400] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"196.441164ms","start":"2026-04-07T17:46:32.338118Z","end":"2026-04-07T17:46:32.53456Z","steps":["trace[1808669400] 'process raft request' (duration: 142.882402ms)","trace[1808669400] 'compare' (duration: 53.444671ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:46:32.535194Z","caller":"traceutil/trace.go:171","msg":"trace[323753224] linearizableReadLoop","detail":"{readStateIndex:326; appliedIndex:324; }","duration":"149.418859ms","start":"2026-04-07T17:46:32.385756Z","end":"2026-04-07T17:46:32.535175Z","steps":["trace[323753224] 'read index received' (duration: 95.277977ms)","trace[323753224] 'applied index is now lower than readState.Index' (duration: 54.139932ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:46:32.535295Z","caller":"traceutil/trace.go:171","msg":"trace[2040944897] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"194.325562ms","start":"2026-04-07T17:46:32.34096Z","end":"2026-04-07T17:46:32.535285Z","steps":["trace[2040944897] 'process raft request' (duration: 194.100449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:46:32.535489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.720263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-04-07T17:46:32.535531Z","caller":"traceutil/trace.go:171","msg":"trace[496223546] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:318; }","duration":"149.824226ms","start":"2026-04-07T17:46:32.385697Z","end":"2026-04-07T17:46:32.535522Z","steps":["trace[496223546] 'agreement among raft nodes before linearized reading' (duration: 149.716624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:46:35.342781Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8857909116630294062,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T17:46:35.609632Z","caller":"traceutil/trace.go:171","msg":"trace[1239367736] linearizableReadLoop","detail":"{readStateIndex:369; appliedIndex:368; }","duration":"766.940344ms","start":"2026-04-07T17:46:34.842659Z","end":"2026-04-07T17:46:35.6096Z","steps":["trace[1239367736] 'read index received' (duration: 766.67619ms)","trace[1239367736] 'applied index is now lower than readState.Index' (duration: 262.844µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:46:35.609759Z","caller":"traceutil/trace.go:171","msg":"trace[1637099432] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"850.658924ms","start":"2026-04-07T17:46:34.759057Z","end":"2026-04-07T17:46:35.609716Z","steps":["trace[1637099432] 'process raft request' (duration: 850.348849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:46:35.609842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.219913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-07T17:46:35.609847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.166317ms","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-04-07T17:46:35.609893Z","caller":"traceutil/trace.go:171","msg":"trace[906657120] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:360; }","duration":"221.315025ms","start":"2026-04-07T17:46:35.388564Z","end":"2026-04-07T17:46:35.609879Z","steps":["trace[906657120] 'agreement among raft nodes before linearized reading' (duration: 221.204944ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:46:35.609911Z","caller":"traceutil/trace.go:171","msg":"trace[165160692] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:360; }","duration":"767.29966ms","start":"2026-04-07T17:46:34.842595Z","end":"2026-04-07T17:46:35.609895Z","steps":["trace[165160692] 'agreement among raft nodes before linearized reading' (duration: 767.140577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:46:35.610007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:46:34.842553Z","time spent":"767.434861ms","remote":"127.0.0.1:34132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T17:46:35.610338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:46:34.759015Z","time spent":"850.841855ms","remote":"127.0.0.1:34132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:46:35.84118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.437874ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T17:46:35.841289Z","caller":"traceutil/trace.go:171","msg":"trace[1315704592] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"226.34572ms","start":"2026-04-07T17:46:35.614926Z","end":"2026-04-07T17:46:35.841271Z","steps":["trace[1315704592] 'process raft request' (duration: 111.566301ms)","trace[1315704592] 'compare' (duration: 114.318872ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:46:36.359543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.581703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.63\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-07T17:46:36.359575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.133294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-07T17:46:36.359642Z","caller":"traceutil/trace.go:171","msg":"trace[288771779] range","detail":"{range_begin:/registry/masterleases/199.19.213.63; range_end:; response_count:1; response_revision:362; }","duration":"213.731575ms","start":"2026-04-07T17:46:36.145888Z","end":"2026-04-07T17:46:36.359619Z","steps":["trace[288771779] 'range keys from in-memory index tree' (duration: 213.453031ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:46:36.359666Z","caller":"traceutil/trace.go:171","msg":"trace[1748954891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:362; }","duration":"192.268087ms","start":"2026-04-07T17:46:36.167363Z","end":"2026-04-07T17:46:36.359631Z","steps":["trace[1748954891] 'range keys from in-memory index tree' (duration: 191.980203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:46:36.359573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.497361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-07T17:46:36.359918Z","caller":"traceutil/trace.go:171","msg":"trace[696910117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:362; }","duration":"190.887927ms","start":"2026-04-07T17:46:36.169012Z","end":"2026-04-07T17:46:36.3599Z","steps":["trace[696910117] 'range keys from in-memory index tree' (duration: 190.269617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:46:36.901255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:46:36.360671Z","time spent":"540.578722ms","remote":"127.0.0.1:33922","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-07T17:46:37.840643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.536361ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T17:46:37.840952Z","caller":"traceutil/trace.go:171","msg":"trace[465941411] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"1.476574744s","start":"2026-04-07T17:46:36.36434Z","end":"2026-04-07T17:46:37.840915Z","steps":["trace[465941411] 'process raft request' (duration: 1.143613876s)","trace[465941411] 'compare' (duration: 331.941243ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:46:37.841138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:46:36.364322Z","time spent":"1.476711065s","remote":"127.0.0.1:34132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:46:37.841113Z","caller":"traceutil/trace.go:171","msg":"trace[1999665119] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"1.475176552s","start":"2026-04-07T17:46:36.365884Z","end":"2026-04-07T17:46:37.84106Z","steps":["trace[1999665119] 'process raft request' (duration: 1.474905488s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:46:37.841358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:46:36.365872Z","time spent":"1.475355025s","remote":"127.0.0.1:34132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:46:37.840967Z","caller":"traceutil/trace.go:171","msg":"trace[1878755414] linearizableReadLoop","detail":"{readStateIndex:375; appliedIndex:372; }","duration":"151.299734ms","start":"2026-04-07T17:46:37.689642Z","end":"2026-04-07T17:46:37.840942Z","steps":["trace[1878755414] 'read index received' (duration: 61.672µs)","trace[1878755414] 'applied index is now lower than readState.Index' (duration: 151.236722ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:46:37.841376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.72546ms","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-04-07T17:46:37.841537Z","caller":"traceutil/trace.go:171","msg":"trace[738943213] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:365; }","duration":"151.924243ms","start":"2026-04-07T17:46:37.689597Z","end":"2026-04-07T17:46:37.841521Z","steps":["trace[738943213] 'agreement among raft nodes before linearized reading' (duration: 151.655579ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:46:37.841201Z","caller":"traceutil/trace.go:171","msg":"trace[266132976] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"938.426598ms","start":"2026-04-07T17:46:36.902727Z","end":"2026-04-07T17:46:37.841154Z","steps":["trace[266132976] 'process raft request' (duration: 938.162955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:46:37.841839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:46:36.902689Z","time spent":"938.998806ms","remote":"127.0.0.1:33922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:46:39.656409Z","caller":"traceutil/trace.go:171","msg":"trace[235674618] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"126.759872ms","start":"2026-04-07T17:46:39.529613Z","end":"2026-04-07T17:46:39.656373Z","steps":["trace[235674618] 'process raft request' (duration: 126.468138ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:46:40.830498Z","caller":"traceutil/trace.go:171","msg":"trace[129159357] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"123.496811ms","start":"2026-04-07T17:46:40.706983Z","end":"2026-04-07T17:46:40.83048Z","steps":["trace[129159357] 'process raft request' (duration: 123.36263ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:46:42.174513Z","caller":"traceutil/trace.go:171","msg":"trace[1585610276] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:386; }","duration":"241.381318ms","start":"2026-04-07T17:46:41.933105Z","end":"2026-04-07T17:46:42.174486Z","steps":["trace[1585610276] 'read index received' (duration: 27.039188ms)","trace[1585610276] 'applied index is now lower than readState.Index' (duration: 214.34081ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:46:42.174563Z","caller":"traceutil/trace.go:171","msg":"trace[1992480113] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"257.311393ms","start":"2026-04-07T17:46:41.91723Z","end":"2026-04-07T17:46:42.174542Z","steps":["trace[1992480113] 'process raft request' (duration: 255.11808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:46:42.174803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.662812ms","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-04-07T17:46:42.174931Z","caller":"traceutil/trace.go:171","msg":"trace[1054746098] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:377; }","duration":"241.833235ms","start":"2026-04-07T17:46:41.933075Z","end":"2026-04-07T17:46:42.174908Z","steps":["trace[1054746098] 'agreement among raft nodes before linearized reading' (duration: 241.56507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:46:42.174892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.512649ms","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-04-07T17:46:42.175024Z","caller":"traceutil/trace.go:171","msg":"trace[639224551] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:377; }","duration":"232.66112ms","start":"2026-04-07T17:46:41.942341Z","end":"2026-04-07T17:46:42.175002Z","steps":["trace[639224551] 'agreement among raft nodes before linearized reading' (duration: 232.439737ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:46:55.82907Z","caller":"traceutil/trace.go:171","msg":"trace[1834839611] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"224.68384ms","start":"2026-04-07T17:46:55.604281Z","end":"2026-04-07T17:46:55.828964Z","steps":["trace[1834839611] 'process raft request' (duration: 224.505782ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:46:55.840649Z","caller":"traceutil/trace.go:171","msg":"trace[70267484] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"232.824401ms","start":"2026-04-07T17:46:55.607802Z","end":"2026-04-07T17:46:55.840627Z","steps":["trace[70267484] 'process raft request' (duration: 232.722586ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:46:55.840895Z","caller":"traceutil/trace.go:171","msg":"trace[456280723] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"191.820813ms","start":"2026-04-07T17:46:55.649062Z","end":"2026-04-07T17:46:55.840883Z","steps":["trace[456280723] 'process raft request' (duration: 191.637355ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:46:55.840888Z","caller":"traceutil/trace.go:171","msg":"trace[256485239] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"199.183642ms","start":"2026-04-07T17:46:55.641672Z","end":"2026-04-07T17:46:55.840856Z","steps":["trace[256485239] 'process raft request' (duration: 198.982373ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:46:55.840956Z","caller":"traceutil/trace.go:171","msg":"trace[722958141] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"195.370527ms","start":"2026-04-07T17:46:55.645579Z","end":"2026-04-07T17:46:55.840949Z","steps":["trace[722958141] 'process raft request' (duration: 195.098035ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:46:55.840943Z","caller":"traceutil/trace.go:171","msg":"trace[209946477] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"179.974332ms","start":"2026-04-07T17:46:55.660944Z","end":"2026-04-07T17:46:55.840919Z","steps":["trace[209946477] 'process raft request' (duration: 179.826355ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:46:55.840977Z","caller":"traceutil/trace.go:171","msg":"trace[431999100] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"213.854599ms","start":"2026-04-07T17:46:55.627108Z","end":"2026-04-07T17:46:55.840963Z","steps":["trace[431999100] 'process raft request' (duration: 213.505125ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:46:55.84099Z","caller":"traceutil/trace.go:171","msg":"trace[375135390] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"233.120843ms","start":"2026-04-07T17:46:55.607862Z","end":"2026-04-07T17:46:55.840983Z","steps":["trace[375135390] 'process raft request' (duration: 232.723566ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:46:55.840967Z","caller":"traceutil/trace.go:171","msg":"trace[1085007078] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"185.14651ms","start":"2026-04-07T17:46:55.655792Z","end":"2026-04-07T17:46:55.840938Z","steps":["trace[1085007078] 'process raft request' (duration: 184.940602ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:46:55.84099Z","caller":"traceutil/trace.go:171","msg":"trace[1165059151] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"163.584964ms","start":"2026-04-07T17:46:55.677365Z","end":"2026-04-07T17:46:55.84095Z","steps":["trace[1165059151] 'process raft request' (duration: 163.444477ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:46:55.84101Z","caller":"traceutil/trace.go:171","msg":"trace[650046368] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"203.018988ms","start":"2026-04-07T17:46:55.637958Z","end":"2026-04-07T17:46:55.840977Z","steps":["trace[650046368] 'process raft request' (duration: 202.676044ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:46:57.588745Z","caller":"traceutil/trace.go:171","msg":"trace[1269481986] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:567; }","duration":"104.453573ms","start":"2026-04-07T17:46:57.484267Z","end":"2026-04-07T17:46:57.58872Z","steps":["trace[1269481986] 'read index received' (duration: 104.085828ms)","trace[1269481986] 'applied index is now lower than readState.Index' (duration: 366.865µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:46:57.588837Z","caller":"traceutil/trace.go:171","msg":"trace[110043493] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"150.22436ms","start":"2026-04-07T17:46:57.438581Z","end":"2026-04-07T17:46:57.588805Z","steps":["trace[110043493] 'process raft request' (duration: 149.895277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:46:57.588926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.649801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:15688"} {"level":"info","ts":"2026-04-07T17:46:57.58897Z","caller":"traceutil/trace.go:171","msg":"trace[510479884] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:550; }","duration":"104.746545ms","start":"2026-04-07T17:46:57.484211Z","end":"2026-04-07T17:46:57.588958Z","steps":["trace[510479884] 'agreement among raft nodes before linearized reading' (duration: 104.601489ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:01.910543Z","caller":"traceutil/trace.go:171","msg":"trace[314569875] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"102.512073ms","start":"2026-04-07T17:47:01.807993Z","end":"2026-04-07T17:47:01.910505Z","steps":["trace[314569875] 'process raft request' (duration: 101.879138ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:02.55387Z","caller":"traceutil/trace.go:171","msg":"trace[492881635] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"206.872526ms","start":"2026-04-07T17:47:02.346969Z","end":"2026-04-07T17:47:02.553842Z","steps":["trace[492881635] 'process raft request' (duration: 206.575084ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:02.554279Z","caller":"traceutil/trace.go:171","msg":"trace[450313808] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:598; }","duration":"167.817283ms","start":"2026-04-07T17:47:02.386421Z","end":"2026-04-07T17:47:02.554238Z","steps":["trace[450313808] 'read index received' (duration: 167.803123ms)","trace[450313808] 'applied index is now lower than readState.Index' (duration: 11.68µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:47:02.554461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.954428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:47:02.554709Z","caller":"traceutil/trace.go:171","msg":"trace[318514259] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:579; }","duration":"168.131575ms","start":"2026-04-07T17:47:02.386378Z","end":"2026-04-07T17:47:02.55451Z","steps":["trace[318514259] 'agreement among raft nodes before linearized reading' (duration: 167.956098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.054685Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8857909116630294495,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T17:47:03.205228Z","caller":"traceutil/trace.go:171","msg":"trace[1295111068] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"650.823037ms","start":"2026-04-07T17:47:02.554375Z","end":"2026-04-07T17:47:03.205198Z","steps":["trace[1295111068] 'read index received' (duration: 649.964734ms)","trace[1295111068] 'applied index is now lower than readState.Index' (duration: 856.853µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:47:03.20553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.995843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:47:03.205576Z","caller":"traceutil/trace.go:171","msg":"trace[366841635] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:580; }","duration":"819.096498ms","start":"2026-04-07T17:47:02.386468Z","end":"2026-04-07T17:47:03.205565Z","steps":["trace[366841635] 'agreement among raft nodes before linearized reading' (duration: 819.001314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.205642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:02.386446Z","time spent":"819.182251ms","remote":"127.0.0.1:37450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-07T17:47:03.205918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.751251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:47:03.206004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.41577ms","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-04-07T17:47:03.206011Z","caller":"traceutil/trace.go:171","msg":"trace[1086943176] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:580; }","duration":"786.882426ms","start":"2026-04-07T17:47:02.419103Z","end":"2026-04-07T17:47:03.205986Z","steps":["trace[1086943176] 'agreement among raft nodes before linearized reading' (duration: 786.713829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.206063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:02.419093Z","time spent":"786.95915ms","remote":"127.0.0.1:37454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 "} {"level":"info","ts":"2026-04-07T17:47:03.206087Z","caller":"traceutil/trace.go:171","msg":"trace[722360051] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"714.164137ms","start":"2026-04-07T17:47:02.491901Z","end":"2026-04-07T17:47:03.206065Z","steps":["trace[722360051] 'process raft request' (duration: 712.64484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.206206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:02.49186Z","time spent":"714.27318ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:47:03.206228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.762755ms","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-04-07T17:47:03.206288Z","caller":"traceutil/trace.go:171","msg":"trace[397980324] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:580; }","duration":"801.855118ms","start":"2026-04-07T17:47:02.404416Z","end":"2026-04-07T17:47:03.206271Z","steps":["trace[397980324] 'agreement among raft nodes before linearized reading' (duration: 801.659051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.206344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:02.404347Z","time spent":"801.985173ms","remote":"127.0.0.1:34132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T17:47:03.206503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.344621ms","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":"warn","ts":"2026-04-07T17:47:03.206538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.560828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:47:03.206549Z","caller":"traceutil/trace.go:171","msg":"trace[1167220227] 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":"643.392752ms","start":"2026-04-07T17:47:02.563145Z","end":"2026-04-07T17:47:03.206538Z","steps":["trace[1167220227] 'agreement among raft nodes before linearized reading' (duration: 643.323799ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:03.206574Z","caller":"traceutil/trace.go:171","msg":"trace[2132214385] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:580; }","duration":"391.599009ms","start":"2026-04-07T17:47:02.814964Z","end":"2026-04-07T17:47:03.206563Z","steps":["trace[2132214385] 'agreement among raft nodes before linearized reading' (duration: 391.541607ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:03.206571Z","caller":"traceutil/trace.go:171","msg":"trace[1907876370] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:580; }","duration":"819.492312ms","start":"2026-04-07T17:47:02.386536Z","end":"2026-04-07T17:47:03.206028Z","steps":["trace[1907876370] 'agreement among raft nodes before linearized reading' (duration: 819.433071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.206602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.717329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:47:03.206642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.787744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:47:03.20665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:02.386495Z","time spent":"820.139797ms","remote":"127.0.0.1:37450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-07T17:47:03.206663Z","caller":"traceutil/trace.go:171","msg":"trace[1670812000] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:580; }","duration":"792.807632ms","start":"2026-04-07T17:47:02.413838Z","end":"2026-04-07T17:47:03.206646Z","steps":["trace[1670812000] 'agreement among raft nodes before linearized reading' (duration: 792.718919ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:03.206677Z","caller":"traceutil/trace.go:171","msg":"trace[1762070794] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"107.844956ms","start":"2026-04-07T17:47:03.098824Z","end":"2026-04-07T17:47:03.206669Z","steps":["trace[1762070794] 'agreement among raft nodes before linearized reading' (duration: 107.798744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.206721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.066935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rwvgj\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-04-07T17:47:03.206712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:02.413818Z","time spent":"792.880156ms","remote":"127.0.0.1:37454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"info","ts":"2026-04-07T17:47:03.206784Z","caller":"traceutil/trace.go:171","msg":"trace[1471772897] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rwvgj; range_end:; response_count:1; response_revision:580; }","duration":"416.147987ms","start":"2026-04-07T17:47:02.790627Z","end":"2026-04-07T17:47:03.206775Z","steps":["trace[1471772897] 'agreement among raft nodes before linearized reading' (duration: 416.056173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.206581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:02.563084Z","time spent":"643.488486ms","remote":"127.0.0.1:37456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-07T17:47:03.20681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:02.790615Z","time spent":"416.18926ms","remote":"127.0.0.1:34046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-rwvgj\" "} {"level":"warn","ts":"2026-04-07T17:47:03.206863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.054379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:47:03.2069Z","caller":"traceutil/trace.go:171","msg":"trace[946504461] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:580; }","duration":"239.10608ms","start":"2026-04-07T17:47:02.967785Z","end":"2026-04-07T17:47:03.206891Z","steps":["trace[946504461] 'agreement among raft nodes before linearized reading' (duration: 239.052359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.206974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.654844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:47:03.206973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.098703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:47:03.207005Z","caller":"traceutil/trace.go:171","msg":"trace[1100740145] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:580; }","duration":"417.700486ms","start":"2026-04-07T17:47:02.789295Z","end":"2026-04-07T17:47:03.206995Z","steps":["trace[1100740145] 'agreement among raft nodes before linearized reading' (duration: 417.652215ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:03.207023Z","caller":"traceutil/trace.go:171","msg":"trace[985200686] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:580; }","duration":"793.181596ms","start":"2026-04-07T17:47:02.413826Z","end":"2026-04-07T17:47:03.207007Z","steps":["trace[985200686] 'agreement among raft nodes before linearized reading' (duration: 793.104414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.207033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.690103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:47:03.207043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:02.789289Z","time spent":"417.745948ms","remote":"127.0.0.1:37492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-04-07T17:47:03.207061Z","caller":"traceutil/trace.go:171","msg":"trace[1194882993] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:580; }","duration":"239.731065ms","start":"2026-04-07T17:47:02.967322Z","end":"2026-04-07T17:47:03.207053Z","steps":["trace[1194882993] 'agreement among raft nodes before linearized reading' (duration: 239.690654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.207068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:02.413805Z","time spent":"793.24956ms","remote":"127.0.0.1:37454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-07T17:47:03.207183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.903604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:47:03.207221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.11041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:47:03.207186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.874419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:47:03.207249Z","caller":"traceutil/trace.go:171","msg":"trace[1629410976] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:580; }","duration":"239.959093ms","start":"2026-04-07T17:47:02.967283Z","end":"2026-04-07T17:47:03.207242Z","steps":["trace[1629410976] 'agreement among raft nodes before linearized reading' (duration: 239.88005ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:03.20725Z","caller":"traceutil/trace.go:171","msg":"trace[1192944715] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:580; }","duration":"256.154672ms","start":"2026-04-07T17:47:02.951087Z","end":"2026-04-07T17:47:03.207242Z","steps":["trace[1192944715] 'agreement among raft nodes before linearized reading' (duration: 256.11649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.206621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.473432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:47:03.206658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.598807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:47:03.2073Z","caller":"traceutil/trace.go:171","msg":"trace[1706484842] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:580; }","duration":"393.176089ms","start":"2026-04-07T17:47:02.814106Z","end":"2026-04-07T17:47:03.207283Z","steps":["trace[1706484842] 'agreement among raft nodes before linearized reading' (duration: 392.493714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.206605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:02.814938Z","time spent":"391.658972ms","remote":"127.0.0.1:37492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-07T17:47:03.207366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:02.814084Z","time spent":"393.255733ms","remote":"127.0.0.1:37496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-07T17:47:03.207379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.378371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:47:03.207407Z","caller":"traceutil/trace.go:171","msg":"trace[798683751] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:580; }","duration":"256.475984ms","start":"2026-04-07T17:47:02.950923Z","end":"2026-04-07T17:47:03.207399Z","steps":["trace[798683751] 'agreement among raft nodes before linearized reading' (duration: 256.434502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.207441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.907619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:47:03.207467Z","caller":"traceutil/trace.go:171","msg":"trace[1953477974] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:580; }","duration":"453.950991ms","start":"2026-04-07T17:47:02.753508Z","end":"2026-04-07T17:47:03.207458Z","steps":["trace[1953477974] 'agreement among raft nodes before linearized reading' (duration: 453.917779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.207495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:02.75349Z","time spent":"453.997044ms","remote":"127.0.0.1:37482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-07T17:47:03.207636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.950803ms","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:5"} {"level":"warn","ts":"2026-04-07T17:47:03.207686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.203062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:47:03.207652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.779525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:47:03.207715Z","caller":"traceutil/trace.go:171","msg":"trace[1080951372] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:580; }","duration":"470.859287ms","start":"2026-04-07T17:47:02.736849Z","end":"2026-04-07T17:47:03.207708Z","steps":["trace[1080951372] 'agreement among raft nodes before linearized reading' (duration: 470.779254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.207751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.272173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:47:03.207751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:02.736842Z","time spent":"470.898938ms","remote":"127.0.0.1:37474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 "} {"level":"info","ts":"2026-04-07T17:47:03.207774Z","caller":"traceutil/trace.go:171","msg":"trace[1877912405] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:580; }","duration":"471.314136ms","start":"2026-04-07T17:47:02.736453Z","end":"2026-04-07T17:47:03.207767Z","steps":["trace[1877912405] 'agreement among raft nodes before linearized reading' (duration: 471.282194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.207803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:02.736437Z","time spent":"471.356987ms","remote":"127.0.0.1:37474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-04-07T17:47:03.20731Z","caller":"traceutil/trace.go:171","msg":"trace[971306407] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:580; }","duration":"393.248032ms","start":"2026-04-07T17:47:02.81405Z","end":"2026-04-07T17:47:03.207298Z","steps":["trace[971306407] 'agreement among raft nodes before linearized reading' (duration: 392.588547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.207862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:02.814033Z","time spent":"393.815032ms","remote":"127.0.0.1:37496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T17:47:03.207888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.092602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:47:03.207913Z","caller":"traceutil/trace.go:171","msg":"trace[509915665] 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":"496.135124ms","start":"2026-04-07T17:47:02.71177Z","end":"2026-04-07T17:47:03.207906Z","steps":["trace[509915665] 'agreement among raft nodes before linearized reading' (duration: 496.098872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.207942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:02.711759Z","time spent":"496.173955ms","remote":"127.0.0.1:37456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 "} {"level":"warn","ts":"2026-04-07T17:47:03.207375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.139741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:47:03.207961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.63434ms","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:5"} {"level":"info","ts":"2026-04-07T17:47:03.207989Z","caller":"traceutil/trace.go:171","msg":"trace[1580254269] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:580; }","duration":"453.777174ms","start":"2026-04-07T17:47:02.7542Z","end":"2026-04-07T17:47:03.207977Z","steps":["trace[1580254269] 'agreement among raft nodes before linearized reading' (duration: 453.150571ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:03.208002Z","caller":"traceutil/trace.go:171","msg":"trace[1723213784] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:580; }","duration":"273.691813ms","start":"2026-04-07T17:47:02.934299Z","end":"2026-04-07T17:47:03.20799Z","steps":["trace[1723213784] 'agreement among raft nodes before linearized reading' (duration: 273.63168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.208025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:02.754188Z","time spent":"453.825256ms","remote":"127.0.0.1:37482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 "} {"level":"info","ts":"2026-04-07T17:47:03.207716Z","caller":"traceutil/trace.go:171","msg":"trace[1738361938] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:580; }","duration":"471.244062ms","start":"2026-04-07T17:47:02.736463Z","end":"2026-04-07T17:47:03.207707Z","steps":["trace[1738361938] 'agreement among raft nodes before linearized reading' (duration: 471.206251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.208107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.766477ms","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":"warn","ts":"2026-04-07T17:47:03.208121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.956069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:47:03.2082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.984155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:47:03.208243Z","caller":"traceutil/trace.go:171","msg":"trace[322029675] 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":"497.053118ms","start":"2026-04-07T17:47:02.71118Z","end":"2026-04-07T17:47:03.208233Z","steps":["trace[322029675] 'agreement among raft nodes before linearized reading' (duration: 496.994566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.208267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.065756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:47:03.208296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:02.711162Z","time spent":"497.124711ms","remote":"127.0.0.1:37468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"info","ts":"2026-04-07T17:47:03.207221Z","caller":"traceutil/trace.go:171","msg":"trace[2009798893] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:580; }","duration":"417.973036ms","start":"2026-04-07T17:47:02.78924Z","end":"2026-04-07T17:47:03.207213Z","steps":["trace[2009798893] 'agreement among raft nodes before linearized reading' (duration: 417.919705ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:03.208318Z","caller":"traceutil/trace.go:171","msg":"trace[1214814719] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:580; }","duration":"274.140469ms","start":"2026-04-07T17:47:02.934162Z","end":"2026-04-07T17:47:03.208302Z","steps":["trace[1214814719] 'agreement among raft nodes before linearized reading' (duration: 274.067646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.208345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:02.789221Z","time spent":"419.10652ms","remote":"127.0.0.1:37492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-07T17:47:03.207409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.830417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:47:03.208471Z","caller":"traceutil/trace.go:171","msg":"trace[1715492080] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:580; }","duration":"454.905408ms","start":"2026-04-07T17:47:02.753556Z","end":"2026-04-07T17:47:03.208461Z","steps":["trace[1715492080] 'agreement among raft nodes before linearized reading' (duration: 453.836357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.208488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.304824ms","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":"warn","ts":"2026-04-07T17:47:03.208508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:02.753498Z","time spent":"454.998171ms","remote":"127.0.0.1:37482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-07T17:47:03.20852Z","caller":"traceutil/trace.go:171","msg":"trace[1039122657] 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":"645.378726ms","start":"2026-04-07T17:47:02.563132Z","end":"2026-04-07T17:47:03.20851Z","steps":["trace[1039122657] 'agreement among raft nodes before linearized reading' (duration: 645.328895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.208557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.670458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:47:03.2082Z","caller":"traceutil/trace.go:171","msg":"trace[672480084] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:580; }","duration":"293.043802ms","start":"2026-04-07T17:47:02.915135Z","end":"2026-04-07T17:47:03.208179Z","steps":["trace[672480084] 'agreement among raft nodes before linearized reading' (duration: 292.909677ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:03.209054Z","caller":"traceutil/trace.go:171","msg":"trace[2117095363] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:580; }","duration":"293.162846ms","start":"2026-04-07T17:47:02.91587Z","end":"2026-04-07T17:47:03.209033Z","steps":["trace[2117095363] 'agreement among raft nodes before linearized reading' (duration: 292.647078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.209389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.169674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:47:03.209448Z","caller":"traceutil/trace.go:171","msg":"trace[1880308894] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:580; }","duration":"294.261178ms","start":"2026-04-07T17:47:02.915172Z","end":"2026-04-07T17:47:03.209433Z","steps":["trace[1880308894] 'agreement among raft nodes before linearized reading' (duration: 294.169904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.210237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.372698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:47:03.210278Z","caller":"traceutil/trace.go:171","msg":"trace[1514133021] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:580; }","duration":"324.478293ms","start":"2026-04-07T17:47:02.885791Z","end":"2026-04-07T17:47:03.21027Z","steps":["trace[1514133021] 'agreement among raft nodes before linearized reading' (duration: 324.40918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.210303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:02.88577Z","time spent":"324.526654ms","remote":"127.0.0.1:37504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-07T17:47:03.210311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:02.736455Z","time spent":"471.632887ms","remote":"127.0.0.1:37474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 "} {"level":"warn","ts":"2026-04-07T17:47:03.210442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.672375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:47:03.210468Z","caller":"traceutil/trace.go:171","msg":"trace[511446869] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:580; }","duration":"326.738408ms","start":"2026-04-07T17:47:02.883723Z","end":"2026-04-07T17:47:03.210462Z","steps":["trace[511446869] 'agreement among raft nodes before linearized reading' (duration: 326.697396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.210486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:02.883698Z","time spent":"326.782551ms","remote":"127.0.0.1:37504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T17:47:03.210563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.881554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:47:03.210587Z","caller":"traceutil/trace.go:171","msg":"trace[272399309] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:580; }","duration":"326.925106ms","start":"2026-04-07T17:47:02.883655Z","end":"2026-04-07T17:47:03.21058Z","steps":["trace[272399309] 'agreement among raft nodes before linearized reading' (duration: 326.891884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.210605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:02.88364Z","time spent":"326.960277ms","remote":"127.0.0.1:37504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 "} {"level":"info","ts":"2026-04-07T17:47:03.207685Z","caller":"traceutil/trace.go:171","msg":"trace[919861915] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:580; }","duration":"257.044296ms","start":"2026-04-07T17:47:02.95063Z","end":"2026-04-07T17:47:03.207675Z","steps":["trace[919861915] 'agreement among raft nodes before linearized reading' (duration: 256.934962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.211823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:02.563115Z","time spent":"645.43603ms","remote":"127.0.0.1:37456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-04-07T17:47:03.208153Z","caller":"traceutil/trace.go:171","msg":"trace[1539648903] 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":"496.874452ms","start":"2026-04-07T17:47:02.711267Z","end":"2026-04-07T17:47:03.208141Z","steps":["trace[1539648903] 'agreement among raft nodes before linearized reading' (duration: 496.804629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.211968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:02.711239Z","time spent":"500.719587ms","remote":"127.0.0.1:37468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-04-07T17:47:03.887568Z","caller":"traceutil/trace.go:171","msg":"trace[1822345366] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"674.142642ms","start":"2026-04-07T17:47:03.213393Z","end":"2026-04-07T17:47:03.887535Z","steps":["trace[1822345366] 'process raft request' (duration: 632.358299ms)","trace[1822345366] 'compare' (duration: 41.362878ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:47:03.887693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:03.213381Z","time spent":"674.248726ms","remote":"127.0.0.1:34132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:47:03.941449Z","caller":"traceutil/trace.go:171","msg":"trace[459765877] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"717.018367ms","start":"2026-04-07T17:47:03.224398Z","end":"2026-04-07T17:47:03.941417Z","steps":["trace[459765877] 'process raft request' (duration: 716.751527ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:03.941552Z","caller":"traceutil/trace.go:171","msg":"trace[345121329] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"682.896525ms","start":"2026-04-07T17:47:03.258633Z","end":"2026-04-07T17:47:03.94153Z","steps":["trace[345121329] 'process raft request' (duration: 682.647255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:03.941626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:03.224383Z","time spent":"717.150633ms","remote":"127.0.0.1:34046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:47:03.941658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:03.258607Z","time spent":"682.999619ms","remote":"127.0.0.1:34378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T17:47:04.659019Z","caller":"traceutil/trace.go:171","msg":"trace[1641761149] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"325.239158ms","start":"2026-04-07T17:47:04.333751Z","end":"2026-04-07T17:47:04.65899Z","steps":["trace[1641761149] 'read index received' (duration: 325.202116ms)","trace[1641761149] 'applied index is now lower than readState.Index' (duration: 36.012µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:47:04.659141Z","caller":"traceutil/trace.go:171","msg":"trace[438718065] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"532.69584ms","start":"2026-04-07T17:47:04.126434Z","end":"2026-04-07T17:47:04.65913Z","steps":["trace[438718065] 'process raft request' (duration: 532.368617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:04.659287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:04.126418Z","time spent":"532.751812ms","remote":"127.0.0.1:34132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:47:04.65945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.683694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-07T17:47:04.659534Z","caller":"traceutil/trace.go:171","msg":"trace[1387995697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:584; }","duration":"325.80881ms","start":"2026-04-07T17:47:04.333704Z","end":"2026-04-07T17:47:04.659513Z","steps":["trace[1387995697] 'agreement among raft nodes before linearized reading' (duration: 325.57731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:04.659582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:04.333679Z","time spent":"325.894812ms","remote":"127.0.0.1:34132","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-04-07T17:47:05.1595Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8857909116630294507,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T17:47:05.261044Z","caller":"traceutil/trace.go:171","msg":"trace[1550417390] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"911.058332ms","start":"2026-04-07T17:47:04.349962Z","end":"2026-04-07T17:47:05.26102Z","steps":["trace[1550417390] 'process raft request' (duration: 910.484209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:05.261228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:04.349936Z","time spent":"911.196757ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:47:05.289873Z","caller":"traceutil/trace.go:171","msg":"trace[79038310] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:603; }","duration":"630.746821ms","start":"2026-04-07T17:47:04.659094Z","end":"2026-04-07T17:47:05.28984Z","steps":["trace[79038310] 'read index received' (duration: 601.130718ms)","trace[79038310] 'applied index is now lower than readState.Index' (duration: 29.615043ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:47:05.290198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.429477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:47:05.290259Z","caller":"traceutil/trace.go:171","msg":"trace[1134456456] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"188.552522ms","start":"2026-04-07T17:47:05.101694Z","end":"2026-04-07T17:47:05.290247Z","steps":["trace[1134456456] 'agreement among raft nodes before linearized reading' (duration: 188.436528ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:05.290065Z","caller":"traceutil/trace.go:171","msg":"trace[1250582092] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"625.14409ms","start":"2026-04-07T17:47:04.664888Z","end":"2026-04-07T17:47:05.290032Z","steps":["trace[1250582092] 'process raft request' (duration: 624.761667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:05.290331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.30812ms","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-04-07T17:47:05.290492Z","caller":"traceutil/trace.go:171","msg":"trace[560018908] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:586; }","duration":"731.648271ms","start":"2026-04-07T17:47:04.558828Z","end":"2026-04-07T17:47:05.290476Z","steps":["trace[560018908] 'agreement among raft nodes before linearized reading' (duration: 731.116351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:05.290528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:04.558799Z","time spent":"731.721505ms","remote":"127.0.0.1:34132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T17:47:05.290491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:04.664864Z","time spent":"625.518435ms","remote":"127.0.0.1:34132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:47:06.786397Z","caller":"traceutil/trace.go:171","msg":"trace[1946404662] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"117.651554ms","start":"2026-04-07T17:47:06.668715Z","end":"2026-04-07T17:47:06.786367Z","steps":["trace[1946404662] 'process raft request' (duration: 117.430716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:07.096936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.16896ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T17:47:07.097164Z","caller":"traceutil/trace.go:171","msg":"trace[1976655900] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"269.539386ms","start":"2026-04-07T17:47:06.827602Z","end":"2026-04-07T17:47:07.097141Z","steps":["trace[1976655900] 'process raft request' (duration: 269.435042ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:07.097203Z","caller":"traceutil/trace.go:171","msg":"trace[1877590746] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"286.472521ms","start":"2026-04-07T17:47:06.81068Z","end":"2026-04-07T17:47:07.097152Z","steps":["trace[1877590746] 'process raft request' (duration: 62.0195ms)","trace[1877590746] 'compare' (duration: 223.948252ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:47:08.421218Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8857909116630294551,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T17:47:08.606481Z","caller":"traceutil/trace.go:171","msg":"trace[122231951] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"764.516884ms","start":"2026-04-07T17:47:07.841945Z","end":"2026-04-07T17:47:08.606462Z","steps":["trace[122231951] 'process raft request' (duration: 744.924143ms)","trace[122231951] 'compare' (duration: 19.335781ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:47:08.606462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.19246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rwvgj\" ","response":"range_response_count:1 size:17520"} {"level":"warn","ts":"2026-04-07T17:47:08.606588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:07.84192Z","time spent":"764.620126ms","remote":"127.0.0.1:33932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T17:47:08.606592Z","caller":"traceutil/trace.go:171","msg":"trace[983520856] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rwvgj; range_end:; response_count:1; response_revision:608; }","duration":"791.370628ms","start":"2026-04-07T17:47:07.815203Z","end":"2026-04-07T17:47:08.606573Z","steps":["trace[983520856] 'range keys from in-memory index tree' (duration: 791.033525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:08.606637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:07.815182Z","time spent":"791.443849ms","remote":"127.0.0.1:34046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17543,"request content":"key:\"/registry/pods/kube-system/cilium-rwvgj\" "} {"level":"info","ts":"2026-04-07T17:47:08.61087Z","caller":"traceutil/trace.go:171","msg":"trace[1842638622] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:628; }","duration":"689.987152ms","start":"2026-04-07T17:47:07.920861Z","end":"2026-04-07T17:47:08.610848Z","steps":["trace[1842638622] 'read index received' (duration: 666.013762ms)","trace[1842638622] 'applied index is now lower than readState.Index' (duration: 23.97253ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:47:08.610986Z","caller":"traceutil/trace.go:171","msg":"trace[1895243078] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"247.595692ms","start":"2026-04-07T17:47:08.363355Z","end":"2026-04-07T17:47:08.610951Z","steps":["trace[1895243078] 'process raft request' (duration: 247.367563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:08.611065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.521775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:47:08.611092Z","caller":"traceutil/trace.go:171","msg":"trace[2052925268] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"509.574717ms","start":"2026-04-07T17:47:08.101508Z","end":"2026-04-07T17:47:08.611083Z","steps":["trace[2052925268] 'agreement among raft nodes before linearized reading' (duration: 509.545005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:08.611126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:08.10148Z","time spent":"509.640668ms","remote":"127.0.0.1:43920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T17:47:08.611027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.15194ms","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-04-07T17:47:08.611273Z","caller":"traceutil/trace.go:171","msg":"trace[1987899681] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:610; }","duration":"690.440449ms","start":"2026-04-07T17:47:07.920819Z","end":"2026-04-07T17:47:08.61126Z","steps":["trace[1987899681] 'agreement among raft nodes before linearized reading' (duration: 690.137548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:08.611296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:07.920794Z","time spent":"690.496341ms","remote":"127.0.0.1:34132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T17:47:08.956352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.862419ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T17:47:08.95647Z","caller":"traceutil/trace.go:171","msg":"trace[1608139366] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"341.167029ms","start":"2026-04-07T17:47:08.615286Z","end":"2026-04-07T17:47:08.956453Z","steps":["trace[1608139366] 'read index received' (duration: 228.134785ms)","trace[1608139366] 'applied index is now lower than readState.Index' (duration: 113.030924ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:47:08.956543Z","caller":"traceutil/trace.go:171","msg":"trace[134419731] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"341.316815ms","start":"2026-04-07T17:47:08.615216Z","end":"2026-04-07T17:47:08.956533Z","steps":["trace[134419731] 'process raft request' (duration: 228.189027ms)","trace[134419731] 'compare' (duration: 112.606159ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:47:08.956605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:08.615198Z","time spent":"341.378028ms","remote":"127.0.0.1:34132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:47:08.956824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.516432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:47:08.956927Z","caller":"traceutil/trace.go:171","msg":"trace[432542548] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"341.663738ms","start":"2026-04-07T17:47:08.615246Z","end":"2026-04-07T17:47:08.95691Z","steps":["trace[432542548] 'agreement among raft nodes before linearized reading' (duration: 341.496472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:08.95697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:08.615229Z","time spent":"341.731821ms","remote":"127.0.0.1:43916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T17:47:08.961119Z","caller":"traceutil/trace.go:171","msg":"trace[961528982] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"339.113616ms","start":"2026-04-07T17:47:08.621986Z","end":"2026-04-07T17:47:08.9611Z","steps":["trace[961528982] 'process raft request' (duration: 338.990501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:08.961248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:08.621966Z","time spent":"339.214668ms","remote":"127.0.0.1:34046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17689,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:47:08.96618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.359604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:47:08.967022Z","caller":"traceutil/trace.go:171","msg":"trace[1018206774] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:613; }","duration":"340.228936ms","start":"2026-04-07T17:47:08.626765Z","end":"2026-04-07T17:47:08.966994Z","steps":["trace[1018206774] 'agreement among raft nodes before linearized reading' (duration: 339.329014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:08.968776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:08.626753Z","time spent":"342.006761ms","remote":"127.0.0.1:34132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T17:47:08.967432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.461495ms","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-04-07T17:47:08.968883Z","caller":"traceutil/trace.go:171","msg":"trace[2010158625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:613; }","duration":"178.96064ms","start":"2026-04-07T17:47:08.789904Z","end":"2026-04-07T17:47:08.968865Z","steps":["trace[2010158625] 'agreement among raft nodes before linearized reading' (duration: 177.343181ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:09.325935Z","caller":"traceutil/trace.go:171","msg":"trace[761074879] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"220.930046ms","start":"2026-04-07T17:47:09.104979Z","end":"2026-04-07T17:47:09.325909Z","steps":["trace[761074879] 'process raft request' (duration: 134.928659ms)","trace[761074879] 'compare' (duration: 85.885783ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:47:09.367718Z","caller":"traceutil/trace.go:171","msg":"trace[814724740] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"148.837782ms","start":"2026-04-07T17:47:09.21885Z","end":"2026-04-07T17:47:09.367688Z","steps":["trace[814724740] 'process raft request' (duration: 148.605933ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:09.617073Z","caller":"traceutil/trace.go:171","msg":"trace[1014625722] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"149.97563ms","start":"2026-04-07T17:47:09.46705Z","end":"2026-04-07T17:47:09.617026Z","steps":["trace[1014625722] 'process raft request' (duration: 149.269786ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:11.16748Z","caller":"traceutil/trace.go:171","msg":"trace[1035443926] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"168.9943ms","start":"2026-04-07T17:47:10.998459Z","end":"2026-04-07T17:47:11.167454Z","steps":["trace[1035443926] 'process raft request' (duration: 168.833374ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:11.186316Z","caller":"traceutil/trace.go:171","msg":"trace[298000215] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"180.292598ms","start":"2026-04-07T17:47:11.005995Z","end":"2026-04-07T17:47:11.186288Z","steps":["trace[298000215] 'process raft request' (duration: 180.039719ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:11.186519Z","caller":"traceutil/trace.go:171","msg":"trace[428538911] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"164.198717ms","start":"2026-04-07T17:47:11.022283Z","end":"2026-04-07T17:47:11.186482Z","steps":["trace[428538911] 'process raft request' (duration: 163.893966ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:11.327212Z","caller":"traceutil/trace.go:171","msg":"trace[1516824143] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:678; }","duration":"111.108728ms","start":"2026-04-07T17:47:11.216061Z","end":"2026-04-07T17:47:11.327169Z","steps":["trace[1516824143] 'read index received' (duration: 48.668485ms)","trace[1516824143] 'applied index is now lower than readState.Index' (duration: 62.439502ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:47:11.327424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.351706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-04-07T17:47:11.327377Z","caller":"traceutil/trace.go:171","msg":"trace[610371853] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"125.525587ms","start":"2026-04-07T17:47:11.201813Z","end":"2026-04-07T17:47:11.327338Z","steps":["trace[610371853] 'process raft request' (duration: 125.146054ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:11.327469Z","caller":"traceutil/trace.go:171","msg":"trace[1173238818] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:659; }","duration":"111.438629ms","start":"2026-04-07T17:47:11.216019Z","end":"2026-04-07T17:47:11.327458Z","steps":["trace[1173238818] 'agreement among raft nodes before linearized reading' (duration: 111.293914ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:14.156397Z","caller":"traceutil/trace.go:171","msg":"trace[1103726925] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:713; }","duration":"129.496969ms","start":"2026-04-07T17:47:14.026867Z","end":"2026-04-07T17:47:14.156364Z","steps":["trace[1103726925] 'read index received' (duration: 129.299852ms)","trace[1103726925] 'applied index is now lower than readState.Index' (duration: 195.397µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:47:14.156438Z","caller":"traceutil/trace.go:171","msg":"trace[194535436] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"131.399145ms","start":"2026-04-07T17:47:14.024999Z","end":"2026-04-07T17:47:14.156398Z","steps":["trace[194535436] 'process raft request' (duration: 131.155927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:14.15683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.165887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:47:14.156865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.715214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:47:14.157134Z","caller":"traceutil/trace.go:171","msg":"trace[2062759636] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:693; }","duration":"123.014195ms","start":"2026-04-07T17:47:14.034097Z","end":"2026-04-07T17:47:14.157111Z","steps":["trace[2062759636] 'agreement among raft nodes before linearized reading' (duration: 122.704144ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:14.156915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.027848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rwvgj\" ","response":"range_response_count:1 size:18512"} {"level":"info","ts":"2026-04-07T17:47:14.157266Z","caller":"traceutil/trace.go:171","msg":"trace[887577638] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rwvgj; range_end:; response_count:1; response_revision:693; }","duration":"130.420931ms","start":"2026-04-07T17:47:14.026819Z","end":"2026-04-07T17:47:14.15724Z","steps":["trace[887577638] 'agreement among raft nodes before linearized reading' (duration: 129.666935ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:14.157096Z","caller":"traceutil/trace.go:171","msg":"trace[962139680] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:693; }","duration":"122.431295ms","start":"2026-04-07T17:47:14.034604Z","end":"2026-04-07T17:47:14.157035Z","steps":["trace[962139680] 'agreement among raft nodes before linearized reading' (duration: 122.080213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:14.156653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.481166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:47:14.157683Z","caller":"traceutil/trace.go:171","msg":"trace[1303597483] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:693; }","duration":"123.572184ms","start":"2026-04-07T17:47:14.034097Z","end":"2026-04-07T17:47:14.157669Z","steps":["trace[1303597483] 'agreement among raft nodes before linearized reading' (duration: 122.463866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:23.601251Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8857909116630294829,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T17:47:24.101585Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8857909116630294829,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T17:47:24.3253Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.295206784s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T17:47:24.325966Z","caller":"traceutil/trace.go:171","msg":"trace[1886163820] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"1.295975089s","start":"2026-04-07T17:47:23.029949Z","end":"2026-04-07T17:47:24.325924Z","steps":["trace[1886163820] 'process raft request' (duration: 1.295742342s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:24.326266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:23.029916Z","time spent":"1.296206026s","remote":"127.0.0.1:34132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:47:24.473826Z","caller":"traceutil/trace.go:171","msg":"trace[1205381913] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:777; }","duration":"1.373046052s","start":"2026-04-07T17:47:23.100748Z","end":"2026-04-07T17:47:24.473794Z","steps":["trace[1205381913] 'read index received' (duration: 1.224949337s)","trace[1205381913] 'applied index is now lower than readState.Index' (duration: 148.095245ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:47:24.473948Z","caller":"traceutil/trace.go:171","msg":"trace[808051792] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"1.392896802s","start":"2026-04-07T17:47:23.081036Z","end":"2026-04-07T17:47:24.473933Z","steps":["trace[808051792] 'process raft request' (duration: 1.392646865s)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:24.473928Z","caller":"traceutil/trace.go:171","msg":"trace[2020682135] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"1.443193316s","start":"2026-04-07T17:47:23.030702Z","end":"2026-04-07T17:47:24.473895Z","steps":["trace[2020682135] 'process raft request' (duration: 1.442123212s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:24.474042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.373267299s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:47:24.474078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:23.081011Z","time spent":"1.392988056s","remote":"127.0.0.1:34098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T17:47:24.474078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:23.030674Z","time spent":"1.443329321s","remote":"127.0.0.1:34132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:47:24.47409Z","caller":"traceutil/trace.go:171","msg":"trace[52710174] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:756; }","duration":"1.373378443s","start":"2026-04-07T17:47:23.100697Z","end":"2026-04-07T17:47:24.474075Z","steps":["trace[52710174] 'agreement among raft nodes before linearized reading' (duration: 1.373287659s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:24.474311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:23.100677Z","time spent":"1.37362193s","remote":"127.0.0.1:43916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T17:47:24.474361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.01535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:47:24.474461Z","caller":"traceutil/trace.go:171","msg":"trace[2138142380] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:756; }","duration":"107.193276ms","start":"2026-04-07T17:47:24.36725Z","end":"2026-04-07T17:47:24.474443Z","steps":["trace[2138142380] 'agreement among raft nodes before linearized reading' (duration: 106.951097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:24.474543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.157657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-07T17:47:24.474628Z","caller":"traceutil/trace.go:171","msg":"trace[1832494267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:756; }","duration":"949.256321ms","start":"2026-04-07T17:47:23.525352Z","end":"2026-04-07T17:47:24.474608Z","steps":["trace[1832494267] 'agreement among raft nodes before linearized reading' (duration: 948.982432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:24.474677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:23.525294Z","time spent":"949.373756ms","remote":"127.0.0.1:34132","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-04-07T17:47:24.474966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.157793563s","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-04-07T17:47:24.475006Z","caller":"traceutil/trace.go:171","msg":"trace[130650527] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:756; }","duration":"1.157876317s","start":"2026-04-07T17:47:23.317121Z","end":"2026-04-07T17:47:24.474997Z","steps":["trace[130650527] 'agreement among raft nodes before linearized reading' (duration: 1.157002339s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:24.475047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:23.3171Z","time spent":"1.157937437s","remote":"127.0.0.1:34132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T17:47:24.475187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.410193ms","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-04-07T17:47:24.475235Z","caller":"traceutil/trace.go:171","msg":"trace[1153381168] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:756; }","duration":"947.480404ms","start":"2026-04-07T17:47:23.527742Z","end":"2026-04-07T17:47:24.475222Z","steps":["trace[1153381168] 'agreement among raft nodes before linearized reading' (duration: 946.463612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:24.475266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:23.527732Z","time spent":"947.526325ms","remote":"127.0.0.1:34132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-07T17:47:27.305849Z","caller":"traceutil/trace.go:171","msg":"trace[1134373073] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"217.678476ms","start":"2026-04-07T17:47:27.088147Z","end":"2026-04-07T17:47:27.305825Z","steps":["trace[1134373073] 'process raft request' (duration: 217.508761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:56.308069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.179406ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T17:47:56.308204Z","caller":"traceutil/trace.go:171","msg":"trace[323443068] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"153.42297ms","start":"2026-04-07T17:47:56.154763Z","end":"2026-04-07T17:47:56.308186Z","steps":["trace[323443068] 'process raft request' (duration: 42.035848ms)","trace[323443068] 'compare' (duration: 110.982502ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:49:01.162774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.44279ms","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-04-07T17:49:01.162915Z","caller":"traceutil/trace.go:171","msg":"trace[1747842274] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1205; }","duration":"162.673823ms","start":"2026-04-07T17:49:01.000223Z","end":"2026-04-07T17:49:01.162897Z","steps":["trace[1747842274] 'range keys from in-memory index tree' (duration: 162.276906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:49:01.163228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.40329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-07T17:49:01.163395Z","caller":"traceutil/trace.go:171","msg":"trace[71465572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1205; }","duration":"138.537842ms","start":"2026-04-07T17:49:01.024754Z","end":"2026-04-07T17:49:01.163292Z","steps":["trace[71465572] 'range keys from in-memory index tree' (duration: 138.277197ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:49:01.405262Z","caller":"traceutil/trace.go:171","msg":"trace[1202413289] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"237.790933ms","start":"2026-04-07T17:49:01.167443Z","end":"2026-04-07T17:49:01.405234Z","steps":["trace[1202413289] 'process raft request' (duration: 220.217716ms)","trace[1202413289] 'compare' (duration: 17.403843ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:49:01.405568Z","caller":"traceutil/trace.go:171","msg":"trace[971607167] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"237.905957ms","start":"2026-04-07T17:49:01.167635Z","end":"2026-04-07T17:49:01.405541Z","steps":["trace[971607167] 'process raft request' (duration: 237.556629ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:49:01.405751Z","caller":"traceutil/trace.go:171","msg":"trace[1537975917] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"237.667132ms","start":"2026-04-07T17:49:01.168057Z","end":"2026-04-07T17:49:01.405724Z","steps":["trace[1537975917] 'process raft request' (duration: 237.381847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:49:01.405878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.585548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/default/\" range_end:\"/registry/limitranges/default0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:49:01.405808Z","caller":"traceutil/trace.go:171","msg":"trace[1729901001] linearizableReadLoop","detail":"{readStateIndex:1254; appliedIndex:1249; }","duration":"236.512408ms","start":"2026-04-07T17:49:01.16928Z","end":"2026-04-07T17:49:01.405792Z","steps":["trace[1729901001] 'read index received' (duration: 23.322524ms)","trace[1729901001] 'applied index is now lower than readState.Index' (duration: 213.188644ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:49:01.405927Z","caller":"traceutil/trace.go:171","msg":"trace[1596701357] range","detail":"{range_begin:/registry/limitranges/default/; range_end:/registry/limitranges/default0; response_count:0; response_revision:1210; }","duration":"236.698202ms","start":"2026-04-07T17:49:01.169216Z","end":"2026-04-07T17:49:01.405914Z","steps":["trace[1596701357] 'agreement among raft nodes before linearized reading' (duration: 236.6195ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:49:01.40587Z","caller":"traceutil/trace.go:171","msg":"trace[329800653] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"237.064039ms","start":"2026-04-07T17:49:01.168782Z","end":"2026-04-07T17:49:01.405846Z","steps":["trace[329800653] 'process raft request' (duration: 236.820714ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:49:23.767049Z","caller":"traceutil/trace.go:171","msg":"trace[1128758049] linearizableReadLoop","detail":"{readStateIndex:1374; appliedIndex:1373; }","duration":"187.933452ms","start":"2026-04-07T17:49:23.579086Z","end":"2026-04-07T17:49:23.76702Z","steps":["trace[1128758049] 'read index received' (duration: 97.564756ms)","trace[1128758049] 'applied index is now lower than readState.Index' (duration: 90.367566ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:49:23.767123Z","caller":"traceutil/trace.go:171","msg":"trace[654522567] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"194.62929ms","start":"2026-04-07T17:49:23.572454Z","end":"2026-04-07T17:49:23.767083Z","steps":["trace[654522567] 'process raft request' (duration: 104.118503ms)","trace[654522567] 'compare' (duration: 90.332694ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:49:23.767325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.228097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-07T17:49:23.767409Z","caller":"traceutil/trace.go:171","msg":"trace[525954083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1323; }","duration":"188.353019ms","start":"2026-04-07T17:49:23.579036Z","end":"2026-04-07T17:49:23.767389Z","steps":["trace[525954083] 'agreement among raft nodes before linearized reading' (duration: 188.130744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:49:23.767954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.77765ms","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-04-07T17:49:23.768073Z","caller":"traceutil/trace.go:171","msg":"trace[2019940953] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1323; }","duration":"182.922323ms","start":"2026-04-07T17:49:23.585126Z","end":"2026-04-07T17:49:23.768048Z","steps":["trace[2019940953] 'agreement among raft nodes before linearized reading' (duration: 182.056746ms)"],"step_count":1}