{"level":"warn","ts":"2026-04-02T21:17:47.82822Z","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-02T21:17:47.828351Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.32: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.32:2380","--initial-cluster=instance=https://199.19.213.32:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.32:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.32: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-02T21:17:47.828446Z","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-02T21:17:47.828456Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.32:2380"]} {"level":"info","ts":"2026-04-02T21:17:47.828507Z","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-02T21:17:47.829392Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.32:2379"]} {"level":"info","ts":"2026-04-02T21:17:47.829555Z","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.32:2380"],"listen-peer-urls":["https://199.19.213.32:2380"],"advertise-client-urls":["https://199.19.213.32:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.32:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.32: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-02T21:17:47.83386Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.007424ms"} {"level":"info","ts":"2026-04-02T21:17:47.840413Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e654fe799640d103","cluster-id":"f9a0d2ca39c60e21"} {"level":"info","ts":"2026-04-02T21:17:47.8405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e654fe799640d103 switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T21:17:47.840525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e654fe799640d103 became follower at term 0"} {"level":"info","ts":"2026-04-02T21:17:47.840539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e654fe799640d103 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T21:17:47.840548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e654fe799640d103 became follower at term 1"} {"level":"info","ts":"2026-04-02T21:17:47.840606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e654fe799640d103 switched to configuration voters=(16597170324932448515)"} {"level":"warn","ts":"2026-04-02T21:17:47.845232Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T21:17:47.84647Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T21:17:47.847614Z","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-02T21:17:47.849431Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e654fe799640d103","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T21:17:47.849678Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e654fe799640d103","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T21:17:47.851429Z","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-02T21:17:47.851583Z","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-02T21:17:47.851596Z","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-02T21:17:47.852964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e654fe799640d103 switched to configuration voters=(16597170324932448515)"} {"level":"info","ts":"2026-04-02T21:17:47.853309Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f9a0d2ca39c60e21","local-member-id":"e654fe799640d103","added-peer-id":"e654fe799640d103","added-peer-peer-urls":["https://199.19.213.32:2380"]} {"level":"info","ts":"2026-04-02T21:17:47.856652Z","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-02T21:17:47.856859Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.32:2380"} {"level":"info","ts":"2026-04-02T21:17:47.856917Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e654fe799640d103","initial-advertise-peer-urls":["https://199.19.213.32:2380"],"listen-peer-urls":["https://199.19.213.32:2380"],"advertise-client-urls":["https://199.19.213.32:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.32:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T21:17:47.856984Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T21:17:47.856899Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.32:2380"} {"level":"info","ts":"2026-04-02T21:17:48.141428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e654fe799640d103 is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T21:17:48.141493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e654fe799640d103 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T21:17:48.141524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e654fe799640d103 received MsgPreVoteResp from e654fe799640d103 at term 1"} {"level":"info","ts":"2026-04-02T21:17:48.141542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e654fe799640d103 became candidate at term 2"} {"level":"info","ts":"2026-04-02T21:17:48.141554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e654fe799640d103 received MsgVoteResp from e654fe799640d103 at term 2"} {"level":"info","ts":"2026-04-02T21:17:48.14157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e654fe799640d103 became leader at term 2"} {"level":"info","ts":"2026-04-02T21:17:48.141591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e654fe799640d103 elected leader e654fe799640d103 at term 2"} {"level":"info","ts":"2026-04-02T21:17:48.142754Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T21:17:48.143846Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T21:17:48.143849Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e654fe799640d103","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.32:2379]}","request-path":"/0/members/e654fe799640d103/attributes","cluster-id":"f9a0d2ca39c60e21","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T21:17:48.143903Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T21:17:48.144261Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T21:17:48.144311Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T21:17:48.148149Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f9a0d2ca39c60e21","local-member-id":"e654fe799640d103","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T21:17:48.148346Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T21:17:48.148407Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T21:17:48.148594Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-02T21:17:48.149315Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.32:2379"} {"level":"info","ts":"2026-04-02T21:17:51.159904Z","caller":"traceutil/trace.go:171","msg":"trace[1219714629] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"110.487114ms","start":"2026-04-02T21:17:51.049387Z","end":"2026-04-02T21:17:51.159875Z","steps":["trace[1219714629] 'process raft request' (duration: 102.602108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:17:54.458579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.54799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:17:54.458651Z","caller":"traceutil/trace.go:171","msg":"trace[1551272767] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:0; response_revision:319; }","duration":"289.679719ms","start":"2026-04-02T21:17:54.168958Z","end":"2026-04-02T21:17:54.458638Z","steps":["trace[1551272767] 'range keys from in-memory index tree' (duration: 289.460963ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:17:54.714494Z","caller":"traceutil/trace.go:171","msg":"trace[1995365164] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"249.072278ms","start":"2026-04-02T21:17:54.465399Z","end":"2026-04-02T21:17:54.714471Z","steps":["trace[1995365164] 'process raft request' (duration: 248.950579ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:17:54.853933Z","caller":"traceutil/trace.go:171","msg":"trace[1720105565] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"130.549457ms","start":"2026-04-02T21:17:54.723356Z","end":"2026-04-02T21:17:54.853906Z","steps":["trace[1720105565] 'process raft request' (duration: 45.189213ms)","trace[1720105565] 'compare' (duration: 85.256776ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:18:28.288311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.223145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T21:18:28.288354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.339351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:18:28.288389Z","caller":"traceutil/trace.go:171","msg":"trace[131749000] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:689; }","duration":"141.369831ms","start":"2026-04-02T21:18:28.147006Z","end":"2026-04-02T21:18:28.288375Z","steps":["trace[131749000] 'range keys from in-memory index tree' (duration: 141.085938ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:18:28.288403Z","caller":"traceutil/trace.go:171","msg":"trace[72181458] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:689; }","duration":"322.415875ms","start":"2026-04-02T21:18:27.965972Z","end":"2026-04-02T21:18:28.288388Z","steps":["trace[72181458] 'count revisions from in-memory index tree' (duration: 322.263358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:18:28.288445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:18:27.965955Z","time spent":"322.478678ms","remote":"127.0.0.1:49396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-02T21:18:28.288593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.894436ms","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":"info","ts":"2026-04-02T21:18:28.289277Z","caller":"traceutil/trace.go:171","msg":"trace[540745792] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:689; }","duration":"128.621331ms","start":"2026-04-02T21:18:28.160628Z","end":"2026-04-02T21:18:28.289249Z","steps":["trace[540745792] 'count revisions from in-memory index tree' (duration: 127.759739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:18:28.289028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.793162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:18:28.289514Z","caller":"traceutil/trace.go:171","msg":"trace[631879659] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:689; }","duration":"142.454693ms","start":"2026-04-02T21:18:28.147041Z","end":"2026-04-02T21:18:28.289496Z","steps":["trace[631879659] 'count revisions from in-memory index tree' (duration: 141.406883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:18:28.289008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.38471ms","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-02T21:18:28.28991Z","caller":"traceutil/trace.go:171","msg":"trace[1315978364] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:689; }","duration":"129.314273ms","start":"2026-04-02T21:18:28.160586Z","end":"2026-04-02T21:18:28.2899Z","steps":["trace[1315978364] 'range keys from in-memory index tree' (duration: 128.313066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:18:28.288597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.076598ms","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":"info","ts":"2026-04-02T21:18:28.290141Z","caller":"traceutil/trace.go:171","msg":"trace[1001939775] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:689; }","duration":"128.697674ms","start":"2026-04-02T21:18:28.161428Z","end":"2026-04-02T21:18:28.290126Z","steps":["trace[1001939775] 'range keys from in-memory index tree' (duration: 126.973164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:18:28.290307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.80423ms","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-02T21:18:28.290374Z","caller":"traceutil/trace.go:171","msg":"trace[1896556490] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:689; }","duration":"323.990669ms","start":"2026-04-02T21:18:27.966342Z","end":"2026-04-02T21:18:28.290333Z","steps":["trace[1896556490] 'range keys from in-memory index tree' (duration: 323.41038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:18:28.29041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:18:27.96627Z","time spent":"324.130025ms","remote":"127.0.0.1:49396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-02T21:18:28.290574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.390328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-02T21:18:28.289991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.287607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:18:28.290616Z","caller":"traceutil/trace.go:171","msg":"trace[1792143311] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:689; }","duration":"268.477472ms","start":"2026-04-02T21:18:28.022117Z","end":"2026-04-02T21:18:28.290595Z","steps":["trace[1792143311] 'range keys from in-memory index tree' (duration: 268.179838ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:18:28.290623Z","caller":"traceutil/trace.go:171","msg":"trace[1475491539] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:689; }","duration":"189.921666ms","start":"2026-04-02T21:18:28.100685Z","end":"2026-04-02T21:18:28.290606Z","steps":["trace[1475491539] 'count revisions from in-memory index tree' (duration: 189.177952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:18:28.288316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.346931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:18:28.291609Z","caller":"traceutil/trace.go:171","msg":"trace[913356867] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:689; }","duration":"144.702596ms","start":"2026-04-02T21:18:28.146896Z","end":"2026-04-02T21:18:28.291599Z","steps":["trace[913356867] 'range keys from in-memory index tree' (duration: 141.266026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:18:28.291256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.15116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-02T21:18:28.291828Z","caller":"traceutil/trace.go:171","msg":"trace[1123497057] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:689; }","duration":"214.727788ms","start":"2026-04-02T21:18:28.077058Z","end":"2026-04-02T21:18:28.291786Z","steps":["trace[1123497057] 'range keys from in-memory index tree' (duration: 214.018594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:18:28.292302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.475951ms","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-02T21:18:28.292464Z","caller":"traceutil/trace.go:171","msg":"trace[902611611] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:689; }","duration":"190.65058ms","start":"2026-04-02T21:18:28.101787Z","end":"2026-04-02T21:18:28.292438Z","steps":["trace[902611611] 'range keys from in-memory index tree' (duration: 190.407308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:18:28.293109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.540068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:18:28.293232Z","caller":"traceutil/trace.go:171","msg":"trace[963038239] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:689; }","duration":"178.706626ms","start":"2026-04-02T21:18:28.114493Z","end":"2026-04-02T21:18:28.293199Z","steps":["trace[963038239] 'range keys from in-memory index tree' (duration: 178.400761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:18:28.294059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.379586ms","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-02T21:18:28.294111Z","caller":"traceutil/trace.go:171","msg":"trace[1570091154] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:689; }","duration":"193.479971ms","start":"2026-04-02T21:18:28.100614Z","end":"2026-04-02T21:18:28.294094Z","steps":["trace[1570091154] 'range keys from in-memory index tree' (duration: 193.263551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:18:28.293741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.985138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:18:28.294939Z","caller":"traceutil/trace.go:171","msg":"trace[221565190] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:689; }","duration":"180.005086ms","start":"2026-04-02T21:18:28.114727Z","end":"2026-04-02T21:18:28.294733Z","steps":["trace[221565190] 'count revisions from in-memory index tree' (duration: 178.872073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:18:28.293802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.012219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:18:28.295167Z","caller":"traceutil/trace.go:171","msg":"trace[650986421] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:689; }","duration":"180.378733ms","start":"2026-04-02T21:18:28.114766Z","end":"2026-04-02T21:18:28.295145Z","steps":["trace[650986421] 'range keys from in-memory index tree' (duration: 178.925306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:18:28.293999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.491882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-w8d7s\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-02T21:18:28.295303Z","caller":"traceutil/trace.go:171","msg":"trace[438396178] range","detail":"{range_begin:/registry/pods/kube-system/cilium-w8d7s; range_end:; response_count:1; response_revision:689; }","duration":"205.828884ms","start":"2026-04-02T21:18:28.089462Z","end":"2026-04-02T21:18:28.295291Z","steps":["trace[438396178] 'range keys from in-memory index tree' (duration: 204.377026ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:18:52.35409Z","caller":"traceutil/trace.go:171","msg":"trace[717972450] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"132.666335ms","start":"2026-04-02T21:18:52.221367Z","end":"2026-04-02T21:18:52.354033Z","steps":["trace[717972450] 'process raft request' (duration: 131.979301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:20:12.503294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.298057ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T21:20:12.503443Z","caller":"traceutil/trace.go:171","msg":"trace[1840943887] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"315.28008ms","start":"2026-04-02T21:20:12.188138Z","end":"2026-04-02T21:20:12.503418Z","steps":["trace[1840943887] 'process raft request' (duration: 148.176778ms)","trace[1840943887] 'compare' (duration: 166.070003ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:20:12.503535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:20:12.188096Z","time spent":"315.395503ms","remote":"127.0.0.1:53294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":967,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T21:20:12.594543Z","caller":"traceutil/trace.go:171","msg":"trace[1889008853] linearizableReadLoop","detail":"{readStateIndex:1995; appliedIndex:1994; }","duration":"201.713033ms","start":"2026-04-02T21:20:12.392802Z","end":"2026-04-02T21:20:12.594515Z","steps":["trace[1889008853] 'read index received' (duration: 201.494639ms)","trace[1889008853] 'applied index is now lower than readState.Index' (duration: 217.274µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:20:12.594918Z","caller":"traceutil/trace.go:171","msg":"trace[4099952] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"405.743681ms","start":"2026-04-02T21:20:12.189157Z","end":"2026-04-02T21:20:12.5949Z","steps":["trace[4099952] 'process raft request' (duration: 405.223341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:20:12.595047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:20:12.189126Z","time spent":"405.836333ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T21:20:12.595293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.488758ms","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-02T21:20:12.595347Z","caller":"traceutil/trace.go:171","msg":"trace[1776898413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1920; }","duration":"202.57194ms","start":"2026-04-02T21:20:12.392762Z","end":"2026-04-02T21:20:12.595334Z","steps":["trace[1776898413] 'agreement among raft nodes before linearized reading' (duration: 202.418297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:20:12.5957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.478003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-02T21:20:12.595846Z","caller":"traceutil/trace.go:171","msg":"trace[1402352546] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1920; }","duration":"147.634535ms","start":"2026-04-02T21:20:12.448149Z","end":"2026-04-02T21:20:12.595783Z","steps":["trace[1402352546] 'agreement among raft nodes before linearized reading' (duration: 147.3593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:20:12.595979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.153941ms","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-02T21:20:12.596051Z","caller":"traceutil/trace.go:171","msg":"trace[1961660956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1920; }","duration":"203.270244ms","start":"2026-04-02T21:20:12.392762Z","end":"2026-04-02T21:20:12.596033Z","steps":["trace[1961660956] 'agreement among raft nodes before linearized reading' (duration: 203.018538ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:20:25.168189Z","caller":"traceutil/trace.go:171","msg":"trace[1718034192] transaction","detail":"{read_only:false; response_revision:2023; number_of_response:1; }","duration":"172.167643ms","start":"2026-04-02T21:20:24.995999Z","end":"2026-04-02T21:20:25.168167Z","steps":["trace[1718034192] 'process raft request' (duration: 172.020621ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:20:25.552175Z","caller":"traceutil/trace.go:171","msg":"trace[1063764731] transaction","detail":"{read_only:false; response_revision:2025; number_of_response:1; }","duration":"124.304865ms","start":"2026-04-02T21:20:25.427855Z","end":"2026-04-02T21:20:25.55216Z","steps":["trace[1063764731] 'process raft request' (duration: 124.206563ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:20:41.499545Z","caller":"traceutil/trace.go:171","msg":"trace[2002979366] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"109.946404ms","start":"2026-04-02T21:20:41.389573Z","end":"2026-04-02T21:20:41.499519Z","steps":["trace[2002979366] 'process raft request' (duration: 109.884792ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:20:41.499668Z","caller":"traceutil/trace.go:171","msg":"trace[1828039670] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"121.356896ms","start":"2026-04-02T21:20:41.378277Z","end":"2026-04-02T21:20:41.499634Z","steps":["trace[1828039670] 'process raft request' (duration: 118.960896ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:20:47.786989Z","caller":"traceutil/trace.go:171","msg":"trace[1672627607] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"139.332661ms","start":"2026-04-02T21:20:47.64762Z","end":"2026-04-02T21:20:47.786953Z","steps":["trace[1672627607] 'process raft request' (duration: 139.185518ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:20:47.831455Z","caller":"traceutil/trace.go:171","msg":"trace[1623850350] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"108.116962ms","start":"2026-04-02T21:20:47.723285Z","end":"2026-04-02T21:20:47.831402Z","steps":["trace[1623850350] 'process raft request' (duration: 107.980279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:23:59.332417Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15061054546035334359,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T21:23:59.51793Z","caller":"traceutil/trace.go:171","msg":"trace[1757650874] linearizableReadLoop","detail":"{readStateIndex:3412; appliedIndex:3410; }","duration":"685.902688ms","start":"2026-04-02T21:23:58.831992Z","end":"2026-04-02T21:23:59.517894Z","steps":["trace[1757650874] 'read index received' (duration: 598.097747ms)","trace[1757650874] 'applied index is now lower than readState.Index' (duration: 87.803761ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:23:59.518106Z","caller":"traceutil/trace.go:171","msg":"trace[1976910885] transaction","detail":"{read_only:false; response_revision:3287; number_of_response:1; }","duration":"985.636835ms","start":"2026-04-02T21:23:58.532366Z","end":"2026-04-02T21:23:59.518002Z","steps":["trace[1976910885] 'process raft request' (duration: 897.746143ms)","trace[1976910885] 'compare' (duration: 87.540978ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:23:59.518189Z","caller":"traceutil/trace.go:171","msg":"trace[937646859] transaction","detail":"{read_only:false; response_revision:3288; number_of_response:1; }","duration":"916.598147ms","start":"2026-04-02T21:23:58.601571Z","end":"2026-04-02T21:23:59.518169Z","steps":["trace[937646859] 'process raft request' (duration: 916.254672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:23:59.518247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:23:58.532346Z","time spent":"985.826638ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T21:23:59.518342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:23:58.601551Z","time spent":"916.681807ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T21:23:59.518518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.531006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:23:59.518562Z","caller":"traceutil/trace.go:171","msg":"trace[1233484697] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3288; }","duration":"686.610646ms","start":"2026-04-02T21:23:58.831941Z","end":"2026-04-02T21:23:59.518552Z","steps":["trace[1233484697] 'agreement among raft nodes before linearized reading' (duration: 686.546926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:23:59.518596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:23:58.831921Z","time spent":"686.668388ms","remote":"127.0.0.1:53198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T21:23:59.518631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.762757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T21:23:59.518782Z","caller":"traceutil/trace.go:171","msg":"trace[479083521] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:3288; }","duration":"619.92728ms","start":"2026-04-02T21:23:58.898793Z","end":"2026-04-02T21:23:59.51872Z","steps":["trace[479083521] 'agreement among raft nodes before linearized reading' (duration: 619.725557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:23:59.518668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.056096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-02T21:23:59.51885Z","caller":"traceutil/trace.go:171","msg":"trace[257931696] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3288; }","duration":"645.307079ms","start":"2026-04-02T21:23:58.873529Z","end":"2026-04-02T21:23:59.518836Z","steps":["trace[257931696] 'agreement among raft nodes before linearized reading' (duration: 644.941194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:23:59.518853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:23:58.898771Z","time spent":"620.064401ms","remote":"127.0.0.1:49544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-02T21:23:59.518899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:23:58.873505Z","time spent":"645.372201ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-02T21:24:00.78269Z","caller":"traceutil/trace.go:171","msg":"trace[145647205] transaction","detail":"{read_only:false; response_revision:3296; number_of_response:1; }","duration":"170.444424ms","start":"2026-04-02T21:24:00.612214Z","end":"2026-04-02T21:24:00.782658Z","steps":["trace[145647205] 'process raft request' (duration: 74.151313ms)","trace[145647205] 'compare' (duration: 96.143609ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:24:01.455643Z","caller":"traceutil/trace.go:171","msg":"trace[219589446] transaction","detail":"{read_only:false; response_revision:3298; number_of_response:1; }","duration":"107.996704ms","start":"2026-04-02T21:24:01.34759Z","end":"2026-04-02T21:24:01.455587Z","steps":["trace[219589446] 'process raft request' (duration: 58.388799ms)","trace[219589446] 'compare' (duration: 49.469723ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:24:01.675417Z","caller":"traceutil/trace.go:171","msg":"trace[510688452] linearizableReadLoop","detail":"{readStateIndex:3425; appliedIndex:3423; }","duration":"124.045981ms","start":"2026-04-02T21:24:01.551344Z","end":"2026-04-02T21:24:01.67539Z","steps":["trace[510688452] 'read index received' (duration: 1.720942ms)","trace[510688452] 'applied index is now lower than readState.Index' (duration: 122.323849ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:24:01.675496Z","caller":"traceutil/trace.go:171","msg":"trace[2001482970] transaction","detail":"{read_only:false; response_revision:3300; number_of_response:1; }","duration":"150.83828ms","start":"2026-04-02T21:24:01.52463Z","end":"2026-04-02T21:24:01.675469Z","steps":["trace[2001482970] 'process raft request' (duration: 97.453497ms)","trace[2001482970] 'compare' (duration: 53.08933ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:24:01.675638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.284304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-02T21:24:01.675685Z","caller":"traceutil/trace.go:171","msg":"trace[1020147213] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3300; }","duration":"124.385486ms","start":"2026-04-02T21:24:01.551285Z","end":"2026-04-02T21:24:01.675671Z","steps":["trace[1020147213] 'agreement among raft nodes before linearized reading' (duration: 124.209954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:33.50361Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15061054546035334967,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T21:24:34.003997Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15061054546035334967,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T21:24:34.50451Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15061054546035334967,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T21:24:35.003479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000436132s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-02T21:24:35.003617Z","caller":"traceutil/trace.go:171","msg":"trace[432328296] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000626954s","start":"2026-04-02T21:24:33.002965Z","end":"2026-04-02T21:24:35.003592Z","steps":["trace[432328296] 'agreement among raft nodes before linearized reading' (duration: 2.000464832s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:35.003685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:33.002944Z","time spent":"2.000725626s","remote":"127.0.0.1:53198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T21:24:35.005059Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15061054546035334967,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T21:24:35.505826Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15061054546035334967,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T21:24:35.833423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000338848s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-02T21:24:35.833507Z","caller":"traceutil/trace.go:171","msg":"trace[243863423] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00047485s","start":"2026-04-02T21:24:33.833017Z","end":"2026-04-02T21:24:35.833492Z","steps":["trace[243863423] 'agreement among raft nodes before linearized reading' (duration: 2.000370638s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:35.833579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:33.832988Z","time spent":"2.000581932s","remote":"127.0.0.1:53194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T21:24:36.006868Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15061054546035334967,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T21:24:36.507281Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15061054546035334967,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T21:24:37.007953Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15061054546035334967,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T21:24:37.508884Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15061054546035334967,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T21:24:37.735727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:32.736705Z","time spent":"4.999015784s","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/04/02 21:24:37 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-02T21:24:37.841678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00012411s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-02T21:24:37.841736Z","caller":"traceutil/trace.go:171","msg":"trace[1077213355] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000228912s","start":"2026-04-02T21:24:35.841495Z","end":"2026-04-02T21:24:37.841723Z","steps":["trace[1077213355] 'agreement among raft nodes before linearized reading' (duration: 2.00015058s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:37.841766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:35.841477Z","time spent":"2.000283822s","remote":"127.0.0.1:53198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T21:24:38.009995Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15061054546035334967,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T21:24:38.510135Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15061054546035334967,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T21:24:38.674351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:33.675791Z","time spent":"4.998545151s","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-02T21:24:38.787443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:33.789785Z","time spent":"4.997648778s","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/04/02 21:24:38 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-02T21:24:38.906696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999259648s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-02T21:24:38.906932Z","caller":"traceutil/trace.go:171","msg":"trace[1285739371] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999561692s","start":"2026-04-02T21:24:33.907316Z","end":"2026-04-02T21:24:38.906878Z","steps":["trace[1285739371] 'agreement among raft nodes before linearized reading' (duration: 4.999282799s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:38.907106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:33.907299Z","time spent":"4.999778155s","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/04/02 21:24:38 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-02T21:24:39.010544Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15061054546035334967,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T21:24:39.325311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999113561s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-02T21:24:39.325384Z","caller":"traceutil/trace.go:171","msg":"trace[180460359] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999235093s","start":"2026-04-02T21:24:34.32613Z","end":"2026-04-02T21:24:39.325365Z","steps":["trace[180460359] 'agreement among raft nodes before linearized reading' (duration: 4.999147182s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:39.325427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:34.326094Z","time spent":"4.999324784s","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/04/02 21:24:39 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-02T21:24:39.342561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:34.343881Z","time spent":"4.998675786s","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-02T21:24:39.510675Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15061054546035334967,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T21:24:39.535335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998982869s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-02T21:24:39.535432Z","caller":"traceutil/trace.go:171","msg":"trace[508813678] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.999137871s","start":"2026-04-02T21:24:34.536271Z","end":"2026-04-02T21:24:39.535409Z","steps":["trace[508813678] 'agreement among raft nodes before linearized reading' (duration: 4.99902085s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:39.535505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:34.536248Z","time spent":"4.999241282s","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/04/02 21:24:39 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-02T21:24:39.70918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:32.708518Z","time spent":"7.000654816s","remote":"127.0.0.1:53310","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-02T21:24:39.842391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000075432s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-02T21:24:39.842448Z","caller":"traceutil/trace.go:171","msg":"trace[1273705109] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000171433s","start":"2026-04-02T21:24:37.842264Z","end":"2026-04-02T21:24:39.842435Z","steps":["trace[1273705109] 'agreement among raft nodes before linearized reading' (duration: 2.000095552s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:39.842485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:37.842245Z","time spent":"2.000232944s","remote":"127.0.0.1:53194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/02 21:24:39 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-02T21:24:40.003279Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-04-02T21:24:40.503708Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15061054546035334977,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T21:24:41.003903Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15061054546035334977,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T21:24:41.504098Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15061054546035334977,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T21:24:41.849068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000078175s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-02T21:24:41.849126Z","caller":"traceutil/trace.go:171","msg":"trace[1661931757] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000177477s","start":"2026-04-02T21:24:39.848937Z","end":"2026-04-02T21:24:41.849115Z","steps":["trace[1661931757] 'agreement among raft nodes before linearized reading' (duration: 2.000100186s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:41.849198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:39.848924Z","time spent":"2.000267758s","remote":"127.0.0.1:53198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/02 21:24:41 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-02T21:24:41.960852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:34.959938Z","time spent":"7.000908345s","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-02T21:24:42.004945Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15061054546035334977,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T21:24:42.008192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:35.007576Z","time spent":"7.000610751s","remote":"127.0.0.1:53294","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-02T21:24:42.176919Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"9.468238368s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-02T21:24:42.189243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.980995875s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:24:42.189362Z","caller":"traceutil/trace.go:171","msg":"trace[2031682251] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:3551; }","duration":"5.981125176s","start":"2026-04-02T21:24:36.208218Z","end":"2026-04-02T21:24:42.189343Z","steps":["trace[2031682251] 'range keys from in-memory index tree' (duration: 5.980981305s)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:42.367603Z","caller":"traceutil/trace.go:171","msg":"trace[1764039224] transaction","detail":"{read_only:false; response_revision:3559; number_of_response:1; }","duration":"4.761129783s","start":"2026-04-02T21:24:37.606446Z","end":"2026-04-02T21:24:42.367576Z","steps":["trace[1764039224] 'process raft request' (duration: 4.760808718s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:42.367765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:37.606426Z","time spent":"4.761254435s","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T21:24:42.408468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.536877196s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:24:42.408549Z","caller":"traceutil/trace.go:171","msg":"trace[1428688811] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:3561; }","duration":"2.537017608s","start":"2026-04-02T21:24:39.871517Z","end":"2026-04-02T21:24:42.408535Z","steps":["trace[1428688811] 'agreement among raft nodes before linearized reading' (duration: 2.536853256s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:42.408594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:39.8715Z","time spent":"2.537084498s","remote":"127.0.0.1:39928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-02T21:24:42.408674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.401326772s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T21:24:42.408884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.956181651s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-02T21:24:42.40889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.651960112s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T21:24:42.408929Z","caller":"traceutil/trace.go:171","msg":"trace[281474032] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:3561; }","duration":"2.956256171s","start":"2026-04-02T21:24:39.452656Z","end":"2026-04-02T21:24:42.408911Z","steps":["trace[281474032] 'agreement among raft nodes before linearized reading' (duration: 2.95614222s)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:42.408925Z","caller":"traceutil/trace.go:171","msg":"trace[1359587213] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:3561; }","duration":"6.401749148s","start":"2026-04-02T21:24:36.007139Z","end":"2026-04-02T21:24:42.408888Z","steps":["trace[1359587213] 'agreement among raft nodes before linearized reading' (duration: 6.401331593s)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:42.408965Z","caller":"traceutil/trace.go:171","msg":"trace[1003437062] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:3561; }","duration":"5.652073973s","start":"2026-04-02T21:24:36.756871Z","end":"2026-04-02T21:24:42.408945Z","steps":["trace[1003437062] 'agreement among raft nodes before linearized reading' (duration: 5.651944761s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:42.409036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.991071142s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T21:24:42.409053Z","caller":"traceutil/trace.go:171","msg":"trace[296678217] transaction","detail":"{read_only:false; response_revision:3560; number_of_response:1; }","duration":"1.061161091s","start":"2026-04-02T21:24:41.347852Z","end":"2026-04-02T21:24:42.409013Z","steps":["trace[296678217] 'process raft request' (duration: 1.019713935s)","trace[296678217] 'compare' (duration: 39.984026ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:24:42.409053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:36.756851Z","time spent":"5.652189214s","remote":"127.0.0.1:53378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":31,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-04-02T21:24:42.409067Z","caller":"traceutil/trace.go:171","msg":"trace[299538001] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:3561; }","duration":"2.991137873s","start":"2026-04-02T21:24:39.417919Z","end":"2026-04-02T21:24:42.409057Z","steps":["trace[299538001] 'agreement among raft nodes before linearized reading' (duration: 2.991087363s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:42.40912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:39.4179Z","time spent":"2.991208374s","remote":"127.0.0.1:53548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":5,"response size":31,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-04-02T21:24:42.409168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:41.347831Z","time spent":"1.061252472s","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T21:24:42.409244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.290476651s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-02T21:24:42.408981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:39.452636Z","time spent":"2.956336182s","remote":"127.0.0.1:53570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":91,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-04-02T21:24:42.408999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.146911218s","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-02T21:24:42.409396Z","caller":"traceutil/trace.go:171","msg":"trace[161841800] transaction","detail":"{read_only:false; response_revision:3561; number_of_response:1; }","duration":"396.717302ms","start":"2026-04-02T21:24:42.012667Z","end":"2026-04-02T21:24:42.409385Z","steps":["trace[161841800] 'process raft request' (duration: 395.517097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:42.408751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.0644988s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-02T21:24:42.409454Z","caller":"traceutil/trace.go:171","msg":"trace[1248144922] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3561; }","duration":"3.065251129s","start":"2026-04-02T21:24:39.344186Z","end":"2026-04-02T21:24:42.409437Z","steps":["trace[1248144922] 'agreement among raft nodes before linearized reading' (duration: 3.064379258s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:42.409479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:42.012634Z","time spent":"396.793883ms","remote":"127.0.0.1:53294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T21:24:42.409509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:39.34417Z","time spent":"3.065326111s","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-02T21:24:42.409603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.474264966s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T21:24:42.409649Z","caller":"traceutil/trace.go:171","msg":"trace[2012691968] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:3561; }","duration":"7.474347168s","start":"2026-04-02T21:24:34.935294Z","end":"2026-04-02T21:24:42.409641Z","steps":["trace[2012691968] 'agreement among raft nodes before linearized reading' (duration: 7.474046574s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:42.408535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.107853255s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-02T21:24:42.409717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.150254521s","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-02T21:24:42.409726Z","caller":"traceutil/trace.go:171","msg":"trace[162987545] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:3561; }","duration":"4.10908357s","start":"2026-04-02T21:24:38.300629Z","end":"2026-04-02T21:24:42.409713Z","steps":["trace[162987545] 'agreement among raft nodes before linearized reading' (duration: 4.107713323s)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:42.409767Z","caller":"traceutil/trace.go:171","msg":"trace[710019230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3561; }","duration":"8.150346122s","start":"2026-04-02T21:24:34.259409Z","end":"2026-04-02T21:24:42.409755Z","steps":["trace[710019230] 'agreement among raft nodes before linearized reading' (duration: 8.150233621s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:42.4098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:34.259385Z","time spent":"8.150408052s","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T21:24:42.408719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.604862283s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:24:42.40996Z","caller":"traceutil/trace.go:171","msg":"trace[2064858834] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:3561; }","duration":"5.606077888s","start":"2026-04-02T21:24:36.803777Z","end":"2026-04-02T21:24:42.409855Z","steps":["trace[2064858834] 'agreement among raft nodes before linearized reading' (duration: 5.604835653s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:42.410022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:36.803754Z","time spent":"5.60625024s","remote":"127.0.0.1:39948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "} {"level":"warn","ts":"2026-04-02T21:24:42.409073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:36.007109Z","time spent":"6.401871479s","remote":"127.0.0.1:39938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true "} {"level":"warn","ts":"2026-04-02T21:24:42.409927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.98996886s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:24:42.409282Z","caller":"traceutil/trace.go:171","msg":"trace[105065701] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:3561; }","duration":"6.290547902s","start":"2026-04-02T21:24:36.118721Z","end":"2026-04-02T21:24:42.409269Z","steps":["trace[105065701] 'agreement among raft nodes before linearized reading' (duration: 6.29038154s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:42.410542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:36.118701Z","time spent":"6.291828058s","remote":"127.0.0.1:53262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":61,"response size":31,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-04-02T21:24:42.4106Z","caller":"traceutil/trace.go:171","msg":"trace[515720735] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:3561; }","duration":"7.990474867s","start":"2026-04-02T21:24:34.419904Z","end":"2026-04-02T21:24:42.410379Z","steps":["trace[515720735] 'agreement among raft nodes before linearized reading' (duration: 7.989977361s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:42.410881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:34.419882Z","time spent":"7.990978194s","remote":"127.0.0.1:49440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-02T21:24:42.410929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.804456257s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-04-02T21:24:42.410955Z","caller":"traceutil/trace.go:171","msg":"trace[1152008876] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3561; }","duration":"8.804523847s","start":"2026-04-02T21:24:33.606422Z","end":"2026-04-02T21:24:42.410946Z","steps":["trace[1152008876] 'agreement among raft nodes before linearized reading' (duration: 8.804420806s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:42.410981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:33.606402Z","time spent":"8.804574207s","remote":"127.0.0.1:53412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-02T21:24:42.409801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:38.300611Z","time spent":"4.109174822s","remote":"127.0.0.1:49308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":20,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-04-02T21:24:42.409399Z","caller":"traceutil/trace.go:171","msg":"trace[1920647027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3561; }","duration":"8.147463965s","start":"2026-04-02T21:24:34.261912Z","end":"2026-04-02T21:24:42.409376Z","steps":["trace[1920647027] 'agreement among raft nodes before linearized reading' (duration: 8.146814307s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:42.409674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:34.935249Z","time spent":"7.474417998s","remote":"127.0.0.1:53418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":21,"response size":31,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-04-02T21:24:42.410673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.73510983s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:551"} {"level":"warn","ts":"2026-04-02T21:24:42.410757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.671162951s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-02T21:24:42.4115Z","caller":"traceutil/trace.go:171","msg":"trace[1647879052] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:3561; }","duration":"4.671939252s","start":"2026-04-02T21:24:37.739545Z","end":"2026-04-02T21:24:42.411485Z","steps":["trace[1647879052] 'agreement among raft nodes before linearized reading' (duration: 4.669232877s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:42.411547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:37.739529Z","time spent":"4.672004902s","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":550,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-04-02T21:24:42.411619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:34.26189Z","time spent":"8.149712534s","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T21:24:42.411536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.622886611s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-02T21:24:42.412897Z","caller":"traceutil/trace.go:171","msg":"trace[2040795202] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3561; }","duration":"3.624284568s","start":"2026-04-02T21:24:38.788592Z","end":"2026-04-02T21:24:42.412876Z","steps":["trace[2040795202] 'agreement among raft nodes before linearized reading' (duration: 3.620032044s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:42.412997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:38.788572Z","time spent":"3.62441345s","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-04-02T21:24:42.408323Z","caller":"traceutil/trace.go:171","msg":"trace[225870596] linearizableReadLoop","detail":"{readStateIndex:3696; appliedIndex:3686; }","duration":"2.404919692s","start":"2026-04-02T21:24:40.003374Z","end":"2026-04-02T21:24:42.408293Z","steps":["trace[225870596] 'read index received' (duration: 2.173892579s)","trace[225870596] 'applied index is now lower than readState.Index' (duration: 231.026133ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:24:42.41201Z","caller":"traceutil/trace.go:171","msg":"trace[1586213000] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:3561; }","duration":"3.73579102s","start":"2026-04-02T21:24:38.675496Z","end":"2026-04-02T21:24:42.411287Z","steps":["trace[1586213000] 'agreement among raft nodes before linearized reading' (duration: 3.73503352s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:42.413134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:38.675475Z","time spent":"3.737622713s","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":575,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-04-02T21:24:42.413228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.112984702s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T21:24:42.413267Z","caller":"traceutil/trace.go:171","msg":"trace[1774408174] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:3561; }","duration":"2.113046892s","start":"2026-04-02T21:24:40.30021Z","end":"2026-04-02T21:24:42.413257Z","steps":["trace[1774408174] 'agreement among raft nodes before linearized reading' (duration: 2.112973171s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:42.413295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:40.300192Z","time spent":"2.113095304s","remote":"127.0.0.1:53684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-02T21:24:42.413394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086247124s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-02T21:24:42.413444Z","caller":"traceutil/trace.go:171","msg":"trace[810387995] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3561; }","duration":"1.086318694s","start":"2026-04-02T21:24:41.327111Z","end":"2026-04-02T21:24:42.41343Z","steps":["trace[810387995] 'agreement among raft nodes before linearized reading' (duration: 1.086188833s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:42.413484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:41.327096Z","time spent":"1.086382066s","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T21:24:42.413508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.090541882s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T21:24:42.413536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.087178308s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.32\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-02T21:24:42.413561Z","caller":"traceutil/trace.go:171","msg":"trace[520461453] range","detail":"{range_begin:/registry/masterleases/199.19.213.32; range_end:; response_count:1; response_revision:3561; }","duration":"1.087202228s","start":"2026-04-02T21:24:41.32635Z","end":"2026-04-02T21:24:42.413552Z","steps":["trace[520461453] 'agreement among raft nodes before linearized reading' (duration: 1.087124416s)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:42.413588Z","caller":"traceutil/trace.go:171","msg":"trace[360170555] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:3561; }","duration":"2.090662974s","start":"2026-04-02T21:24:40.322913Z","end":"2026-04-02T21:24:42.413576Z","steps":["trace[360170555] 'agreement among raft nodes before linearized reading' (duration: 2.090495851s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:42.413642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.255017ms","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-02T21:24:42.413684Z","caller":"traceutil/trace.go:171","msg":"trace[1048236108] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:3561; }","duration":"235.335048ms","start":"2026-04-02T21:24:42.178338Z","end":"2026-04-02T21:24:42.413673Z","steps":["trace[1048236108] 'agreement among raft nodes before linearized reading' (duration: 235.273488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:42.413691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.924067933s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:24:42.413744Z","caller":"traceutil/trace.go:171","msg":"trace[1981741908] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:3561; }","duration":"1.924124583s","start":"2026-04-02T21:24:40.489589Z","end":"2026-04-02T21:24:42.413714Z","steps":["trace[1981741908] 'agreement among raft nodes before linearized reading' (duration: 1.924076792s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:42.413776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:40.489568Z","time spent":"1.924199755s","remote":"127.0.0.1:49344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-02T21:24:42.41374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.505542499s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-02T21:24:42.413857Z","caller":"traceutil/trace.go:171","msg":"trace[693851322] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3561; }","duration":"1.505718061s","start":"2026-04-02T21:24:40.908125Z","end":"2026-04-02T21:24:42.413843Z","steps":["trace[693851322] 'agreement among raft nodes before linearized reading' (duration: 1.505232273s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:42.41392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.57621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:24:42.413957Z","caller":"traceutil/trace.go:171","msg":"trace[1170810628] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3561; }","duration":"564.639981ms","start":"2026-04-02T21:24:41.849309Z","end":"2026-04-02T21:24:42.413949Z","steps":["trace[1170810628] 'agreement among raft nodes before linearized reading' (duration: 564.59421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:42.41399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:41.849291Z","time spent":"564.691711ms","remote":"127.0.0.1:53194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T21:24:42.413937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:40.9081Z","time spent":"1.505825753s","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T21:24:42.414516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.188346213s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-02T21:24:42.41463Z","caller":"traceutil/trace.go:171","msg":"trace[1284084174] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:3561; }","duration":"1.188494924s","start":"2026-04-02T21:24:41.226126Z","end":"2026-04-02T21:24:42.414621Z","steps":["trace[1284084174] 'agreement among raft nodes before linearized reading' (duration: 1.18814482s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:42.41473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:41.226106Z","time spent":"1.188613796s","remote":"127.0.0.1:53762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":319,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-04-02T21:24:42.413884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.907747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-04-02T21:24:42.414917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:41.326294Z","time spent":"1.087291449s","remote":"127.0.0.1:53244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.32\" "} {"level":"info","ts":"2026-04-02T21:24:42.414924Z","caller":"traceutil/trace.go:171","msg":"trace[947475764] range","detail":"{range_begin:/registry/leases/ingress-nginx/ingress-nginx-leader; range_end:; response_count:1; response_revision:3561; }","duration":"450.00927ms","start":"2026-04-02T21:24:41.964905Z","end":"2026-04-02T21:24:42.414914Z","steps":["trace[947475764] 'agreement among raft nodes before linearized reading' (duration: 448.914916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:42.415109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:41.964886Z","time spent":"450.213093ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":514,"request content":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" "} {"level":"warn","ts":"2026-04-02T21:24:42.415329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.854977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-02T21:24:42.415359Z","caller":"traceutil/trace.go:171","msg":"trace[1730043677] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3561; }","duration":"878.901638ms","start":"2026-04-02T21:24:41.53645Z","end":"2026-04-02T21:24:42.415352Z","steps":["trace[1730043677] 'agreement among raft nodes before linearized reading' (duration: 878.822887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:42.41538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:41.536436Z","time spent":"878.939748ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-02T21:24:42.413625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:40.322893Z","time spent":"2.090722196s","remote":"127.0.0.1:49310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-04-02T21:24:42.410771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.427940031s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T21:24:42.416271Z","caller":"traceutil/trace.go:171","msg":"trace[281958563] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:3561; }","duration":"8.433469701s","start":"2026-04-02T21:24:33.982786Z","end":"2026-04-02T21:24:42.416255Z","steps":["trace[281958563] 'agreement among raft nodes before linearized reading' (duration: 8.42794868s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:42.416382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:33.982764Z","time spent":"8.433602663s","remote":"127.0.0.1:53328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":12,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-04-02T21:24:42.587064Z","caller":"traceutil/trace.go:171","msg":"trace[1953752104] transaction","detail":"{read_only:false; response_revision:3568; number_of_response:1; }","duration":"148.378116ms","start":"2026-04-02T21:24:42.438648Z","end":"2026-04-02T21:24:42.587026Z","steps":["trace[1953752104] 'process raft request' (duration: 148.316565ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:42.587342Z","caller":"traceutil/trace.go:171","msg":"trace[1902894817] transaction","detail":"{read_only:false; response_revision:3564; number_of_response:1; }","duration":"168.922512ms","start":"2026-04-02T21:24:42.418384Z","end":"2026-04-02T21:24:42.587306Z","steps":["trace[1902894817] 'process raft request' (duration: 168.413385ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:42.587414Z","caller":"traceutil/trace.go:171","msg":"trace[751606693] transaction","detail":"{read_only:false; response_revision:3563; number_of_response:1; }","duration":"169.165315ms","start":"2026-04-02T21:24:42.418226Z","end":"2026-04-02T21:24:42.587391Z","steps":["trace[751606693] 'process raft request' (duration: 168.430775ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:42.587448Z","caller":"traceutil/trace.go:171","msg":"trace[1049562116] transaction","detail":"{read_only:false; response_revision:3567; number_of_response:1; }","duration":"166.537181ms","start":"2026-04-02T21:24:42.4209Z","end":"2026-04-02T21:24:42.587437Z","steps":["trace[1049562116] 'process raft request' (duration: 166.019203ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:42.587482Z","caller":"traceutil/trace.go:171","msg":"trace[1179448633] transaction","detail":"{read_only:false; response_revision:3565; number_of_response:1; }","duration":"167.627184ms","start":"2026-04-02T21:24:42.419836Z","end":"2026-04-02T21:24:42.587463Z","steps":["trace[1179448633] 'process raft request' (duration: 167.004255ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:42.587562Z","caller":"traceutil/trace.go:171","msg":"trace[1667012165] linearizableReadLoop","detail":"{readStateIndex:3699; appliedIndex:3697; }","duration":"170.533672ms","start":"2026-04-02T21:24:42.417015Z","end":"2026-04-02T21:24:42.587549Z","steps":["trace[1667012165] 'read index received' (duration: 20.670087ms)","trace[1667012165] 'applied index is now lower than readState.Index' (duration: 149.862065ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:24:42.587875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.666904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-02T21:24:42.587897Z","caller":"traceutil/trace.go:171","msg":"trace[974446717] transaction","detail":"{read_only:false; response_revision:3562; number_of_response:1; }","duration":"171.078748ms","start":"2026-04-02T21:24:42.416798Z","end":"2026-04-02T21:24:42.587877Z","steps":["trace[974446717] 'process raft request' (duration: 91.799294ms)","trace[974446717] 'compare' (duration: 77.867066ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:24:42.587923Z","caller":"traceutil/trace.go:171","msg":"trace[1883176848] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3568; }","duration":"171.663397ms","start":"2026-04-02T21:24:42.416248Z","end":"2026-04-02T21:24:42.587911Z","steps":["trace[1883176848] 'agreement among raft nodes before linearized reading' (duration: 171.448474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:42.587931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.412025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-02T21:24:42.5874Z","caller":"traceutil/trace.go:171","msg":"trace[208019222] transaction","detail":"{read_only:false; response_revision:3566; number_of_response:1; }","duration":"167.009957ms","start":"2026-04-02T21:24:42.420346Z","end":"2026-04-02T21:24:42.587356Z","steps":["trace[208019222] 'process raft request' (duration: 166.53365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:42.587948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.059399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T21:24:42.588245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.003519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:551"} {"level":"info","ts":"2026-04-02T21:24:42.58828Z","caller":"traceutil/trace.go:171","msg":"trace[451282620] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:3568; }","duration":"168.05255ms","start":"2026-04-02T21:24:42.420218Z","end":"2026-04-02T21:24:42.588271Z","steps":["trace[451282620] 'agreement among raft nodes before linearized reading' (duration: 167.968429ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:42.588274Z","caller":"traceutil/trace.go:171","msg":"trace[208142603] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3568; }","duration":"164.384343ms","start":"2026-04-02T21:24:42.42384Z","end":"2026-04-02T21:24:42.588224Z","steps":["trace[208142603] 'agreement among raft nodes before linearized reading' (duration: 164.001168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:42.588398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.1281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-04-02T21:24:42.588417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.057382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7102"} {"level":"info","ts":"2026-04-02T21:24:42.58845Z","caller":"traceutil/trace.go:171","msg":"trace[19557651] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:3568; }","duration":"171.19177ms","start":"2026-04-02T21:24:42.417244Z","end":"2026-04-02T21:24:42.588436Z","steps":["trace[19557651] 'agreement among raft nodes before linearized reading' (duration: 171.056179ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:42.588455Z","caller":"traceutil/trace.go:171","msg":"trace[259000943] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:3568; }","duration":"169.108313ms","start":"2026-04-02T21:24:42.419334Z","end":"2026-04-02T21:24:42.588443Z","steps":["trace[259000943] 'agreement among raft nodes before linearized reading' (duration: 169.035222ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:42.588164Z","caller":"traceutil/trace.go:171","msg":"trace[1232372859] range","detail":"{range_begin:/registry/leases/ingress-nginx/ingress-nginx-leader; range_end:; response_count:1; response_revision:3568; }","duration":"168.676608ms","start":"2026-04-02T21:24:42.419475Z","end":"2026-04-02T21:24:42.588151Z","steps":["trace[1232372859] 'agreement among raft nodes before linearized reading' (duration: 168.279933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:42.588331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.506499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-04-02T21:24:42.58863Z","caller":"traceutil/trace.go:171","msg":"trace[1013164319] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3568; }","duration":"170.207727ms","start":"2026-04-02T21:24:42.418408Z","end":"2026-04-02T21:24:42.588616Z","steps":["trace[1013164319] 'agreement among raft nodes before linearized reading' (duration: 169.419238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:42.58887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.839838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-02T21:24:42.588921Z","caller":"traceutil/trace.go:171","msg":"trace[1043825982] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3568; }","duration":"171.898219ms","start":"2026-04-02T21:24:42.41701Z","end":"2026-04-02T21:24:42.588908Z","steps":["trace[1043825982] 'agreement among raft nodes before linearized reading' (duration: 170.667524ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:42.7374Z","caller":"traceutil/trace.go:171","msg":"trace[1286845901] transaction","detail":"{read_only:false; response_revision:3570; number_of_response:1; }","duration":"136.822157ms","start":"2026-04-02T21:24:42.600542Z","end":"2026-04-02T21:24:42.737364Z","steps":["trace[1286845901] 'process raft request' (duration: 86.77363ms)","trace[1286845901] 'compare' (duration: 49.855494ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:24:44.721674Z","caller":"traceutil/trace.go:171","msg":"trace[1446666780] linearizableReadLoop","detail":"{readStateIndex:3710; appliedIndex:3709; }","duration":"126.825886ms","start":"2026-04-02T21:24:44.594827Z","end":"2026-04-02T21:24:44.721653Z","steps":["trace[1446666780] 'read index received' (duration: 126.796146ms)","trace[1446666780] 'applied index is now lower than readState.Index' (duration: 28.83µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:24:44.721797Z","caller":"traceutil/trace.go:171","msg":"trace[1280978669] transaction","detail":"{read_only:false; response_revision:3573; number_of_response:1; }","duration":"128.814652ms","start":"2026-04-02T21:24:44.59295Z","end":"2026-04-02T21:24:44.721764Z","steps":["trace[1280978669] 'process raft request' (duration: 128.537678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:44.721868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.02041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-02T21:24:44.721893Z","caller":"traceutil/trace.go:171","msg":"trace[1123798597] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3573; }","duration":"127.086141ms","start":"2026-04-02T21:24:44.594799Z","end":"2026-04-02T21:24:44.721886Z","steps":["trace[1123798597] 'agreement among raft nodes before linearized reading' (duration: 126.962249ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:44.938697Z","caller":"traceutil/trace.go:171","msg":"trace[605482086] transaction","detail":"{read_only:false; response_revision:3574; number_of_response:1; }","duration":"343.716807ms","start":"2026-04-02T21:24:44.594957Z","end":"2026-04-02T21:24:44.938674Z","steps":["trace[605482086] 'process raft request' (duration: 326.274762ms)","trace[605482086] 'compare' (duration: 17.212352ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:24:44.938722Z","caller":"traceutil/trace.go:171","msg":"trace[1995714634] linearizableReadLoop","detail":"{readStateIndex:3715; appliedIndex:3710; }","duration":"216.976112ms","start":"2026-04-02T21:24:44.72173Z","end":"2026-04-02T21:24:44.938706Z","steps":["trace[1995714634] 'read index received' (duration: 199.711279ms)","trace[1995714634] 'applied index is now lower than readState.Index' (duration: 17.263873ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:24:44.938723Z","caller":"traceutil/trace.go:171","msg":"trace[1077920564] transaction","detail":"{read_only:false; response_revision:3575; number_of_response:1; }","duration":"343.687176ms","start":"2026-04-02T21:24:44.595006Z","end":"2026-04-02T21:24:44.938693Z","steps":["trace[1077920564] 'process raft request' (duration: 343.546185ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:44.9388Z","caller":"traceutil/trace.go:171","msg":"trace[1817389860] transaction","detail":"{read_only:false; response_revision:3576; number_of_response:1; }","duration":"343.464404ms","start":"2026-04-02T21:24:44.595326Z","end":"2026-04-02T21:24:44.93879Z","steps":["trace[1817389860] 'process raft request' (duration: 343.269341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:44.938867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:44.594986Z","time spent":"343.799928ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T21:24:44.939053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.146073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-02T21:24:44.939059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.104122ms","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-02T21:24:44.939087Z","caller":"traceutil/trace.go:171","msg":"trace[1636620000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3578; }","duration":"344.217533ms","start":"2026-04-02T21:24:44.594862Z","end":"2026-04-02T21:24:44.939079Z","steps":["trace[1636620000] 'agreement among raft nodes before linearized reading' (duration: 344.123372ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:44.939096Z","caller":"traceutil/trace.go:171","msg":"trace[570794344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3578; }","duration":"344.156132ms","start":"2026-04-02T21:24:44.594929Z","end":"2026-04-02T21:24:44.939085Z","steps":["trace[570794344] 'agreement among raft nodes before linearized reading' (duration: 344.030501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:44.939108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:44.594842Z","time spent":"344.260544ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T21:24:44.939147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:44.594922Z","time spent":"344.217623ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T21:24:44.938875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:44.595315Z","time spent":"343.515764ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T21:24:44.938802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:44.594941Z","time spent":"343.808268ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T21:24:44.938909Z","caller":"traceutil/trace.go:171","msg":"trace[1192353821] transaction","detail":"{read_only:false; response_revision:3577; number_of_response:1; }","duration":"343.566095ms","start":"2026-04-02T21:24:44.595335Z","end":"2026-04-02T21:24:44.938901Z","steps":["trace[1192353821] 'process raft request' (duration: 343.289742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:44.939258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:44.595322Z","time spent":"343.913799ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T21:24:44.938939Z","caller":"traceutil/trace.go:171","msg":"trace[50617368] transaction","detail":"{read_only:false; response_revision:3578; number_of_response:1; }","duration":"343.017147ms","start":"2026-04-02T21:24:44.595914Z","end":"2026-04-02T21:24:44.938931Z","steps":["trace[50617368] 'process raft request' (duration: 342.754984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:44.939331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:44.595905Z","time spent":"343.407342ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T21:24:44.956415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.085072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:24:44.956516Z","caller":"traceutil/trace.go:171","msg":"trace[280756830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3579; }","duration":"124.220203ms","start":"2026-04-02T21:24:44.832273Z","end":"2026-04-02T21:24:44.956493Z","steps":["trace[280756830] 'agreement among raft nodes before linearized reading' (duration: 124.083691ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:44.956495Z","caller":"traceutil/trace.go:171","msg":"trace[185371747] transaction","detail":"{read_only:false; response_revision:3579; number_of_response:1; }","duration":"230.391184ms","start":"2026-04-02T21:24:44.726071Z","end":"2026-04-02T21:24:44.956462Z","steps":["trace[185371747] 'process raft request' (duration: 230.167901ms)"],"step_count":1}