{"level":"warn","ts":"2026-04-07T09:41:51.881159Z","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-07T09:41:51.881278Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129: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-07T09:41:51.88137Z","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-07T09:41:51.881383Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-04-07T09:41:51.881431Z","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-07T09:41:51.882154Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-04-07T09:41:51.882242Z","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":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129: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-07T09:41:51.88722Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.640864ms"} {"level":"info","ts":"2026-04-07T09:41:51.899392Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-04-07T09:41:51.899524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T09:41:51.899618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-04-07T09:41:51.899657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T09:41:51.899759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-04-07T09:41:51.899869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-04-07T09:41:51.906857Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T09:41:51.908858Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T09:41:51.910476Z","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-07T09:41:51.913573Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T09:41:51.913887Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T09:41:51.914115Z","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-07T09:41:51.914206Z","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-07T09:41:51.914233Z","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-07T09:41:51.915182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-04-07T09:41:51.915772Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-04-07T09:41:51.920239Z","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-07T09:41:51.920434Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-04-07T09:41:51.920529Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-04-07T09:41:51.922284Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T09:41:51.922347Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T09:41:52.601152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T09:41:52.601221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T09:41:52.60126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-04-07T09:41:52.60128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-04-07T09:41:52.601292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-04-07T09:41:52.601309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-04-07T09:41:52.601323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-04-07T09:41:52.60267Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T09:41:52.6027Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T09:41:52.602738Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T09:41:52.603114Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T09:41:52.603224Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T09:41:52.603293Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T09:41:52.604452Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T09:41:52.604649Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T09:41:52.604731Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T09:41:52.604826Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T09:41:52.607794Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"warn","ts":"2026-04-07T09:42:22.708388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.263239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"warn","ts":"2026-04-07T09:42:22.7085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.6914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-04-07T09:42:22.708612Z","caller":"traceutil/trace.go:171","msg":"trace[1784221208] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:512; }","duration":"248.573666ms","start":"2026-04-07T09:42:22.460004Z","end":"2026-04-07T09:42:22.708577Z","steps":["trace[1784221208] 'range keys from in-memory index tree' (duration: 248.055004ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:22.708653Z","caller":"traceutil/trace.go:171","msg":"trace[1690063837] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:512; }","duration":"248.925665ms","start":"2026-04-07T09:42:22.459676Z","end":"2026-04-07T09:42:22.708602Z","steps":["trace[1690063837] 'range keys from in-memory index tree' (duration: 248.550396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:22.708713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.487636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T09:42:22.708949Z","caller":"traceutil/trace.go:171","msg":"trace[768393773] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:512; }","duration":"176.818204ms","start":"2026-04-07T09:42:22.532088Z","end":"2026-04-07T09:42:22.708906Z","steps":["trace[768393773] 'range keys from in-memory index tree' (duration: 176.30136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:22.708923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.501759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329944"} {"level":"info","ts":"2026-04-07T09:42:22.709042Z","caller":"traceutil/trace.go:171","msg":"trace[1661040527] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:512; }","duration":"191.665243ms","start":"2026-04-07T09:42:22.517349Z","end":"2026-04-07T09:42:22.709015Z","steps":["trace[1661040527] 'range keys from in-memory index tree' (duration: 191.027207ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:29.957766Z","caller":"traceutil/trace.go:171","msg":"trace[132826251] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"136.026127ms","start":"2026-04-07T09:42:29.821698Z","end":"2026-04-07T09:42:29.957724Z","steps":["trace[132826251] 'process raft request' (duration: 111.975916ms)","trace[132826251] 'compare' (duration: 23.891207ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:42:30.20434Z","caller":"traceutil/trace.go:171","msg":"trace[2065972401] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"161.654437ms","start":"2026-04-07T09:42:30.042651Z","end":"2026-04-07T09:42:30.204306Z","steps":["trace[2065972401] 'process raft request' (duration: 137.445743ms)","trace[2065972401] 'compare' (duration: 24.088481ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:42:30.980661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.21304ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:42:30.980835Z","caller":"traceutil/trace.go:171","msg":"trace[1386572771] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"501.958461ms","start":"2026-04-07T09:42:30.478811Z","end":"2026-04-07T09:42:30.980769Z","steps":["trace[1386572771] 'read index received' (duration: 290.941331ms)","trace[1386572771] 'applied index is now lower than readState.Index' (duration: 211.016039ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:42:30.980874Z","caller":"traceutil/trace.go:171","msg":"trace[1236677803] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"719.57538ms","start":"2026-04-07T09:42:30.261251Z","end":"2026-04-07T09:42:30.980826Z","steps":["trace[1236677803] 'process raft request' (duration: 508.459528ms)","trace[1236677803] 'compare' (duration: 210.058237ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:42:30.981152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.323429ms","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-07T09:42:30.981256Z","caller":"traceutil/trace.go:171","msg":"trace[448869309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:625; }","duration":"502.472723ms","start":"2026-04-07T09:42:30.478759Z","end":"2026-04-07T09:42:30.981232Z","steps":["trace[448869309] 'agreement among raft nodes before linearized reading' (duration: 502.148695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:30.981307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:30.478738Z","time spent":"502.559086ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T09:42:30.981189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:30.261202Z","time spent":"719.866848ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:42:31.008237Z","caller":"traceutil/trace.go:171","msg":"trace[2093635011] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"372.819449ms","start":"2026-04-07T09:42:30.635365Z","end":"2026-04-07T09:42:31.008184Z","steps":["trace[2093635011] 'process raft request' (duration: 372.175644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:31.008396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:30.635298Z","time spent":"372.998504ms","remote":"127.0.0.1:50986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:42:31.136967Z","caller":"traceutil/trace.go:171","msg":"trace[1697836174] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"147.781912ms","start":"2026-04-07T09:42:30.989143Z","end":"2026-04-07T09:42:31.136925Z","steps":["trace[1697836174] 'process raft request' (duration: 147.489115ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:31.136987Z","caller":"traceutil/trace.go:171","msg":"trace[1935603109] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:648; }","duration":"156.091043ms","start":"2026-04-07T09:42:30.980871Z","end":"2026-04-07T09:42:31.136962Z","steps":["trace[1935603109] 'read index received' (duration: 26.395708ms)","trace[1935603109] 'applied index is now lower than readState.Index' (duration: 129.692434ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:42:31.137319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.553693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:42:31.137333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.708951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:42:31.137363Z","caller":"traceutil/trace.go:171","msg":"trace[438252955] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:628; }","duration":"559.632485ms","start":"2026-04-07T09:42:30.577721Z","end":"2026-04-07T09:42:31.137353Z","steps":["trace[438252955] 'agreement among raft nodes before linearized reading' (duration: 559.500612ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:31.137377Z","caller":"traceutil/trace.go:171","msg":"trace[2125330519] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:628; }","duration":"140.786852ms","start":"2026-04-07T09:42:30.996578Z","end":"2026-04-07T09:42:31.137364Z","steps":["trace[2125330519] 'agreement among raft nodes before linearized reading' (duration: 140.69237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:31.137367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.119433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:42:31.137487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.346931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:42:31.137486Z","caller":"traceutil/trace.go:171","msg":"trace[1931174874] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:628; }","duration":"461.257216ms","start":"2026-04-07T09:42:30.676199Z","end":"2026-04-07T09:42:31.137456Z","steps":["trace[1931174874] 'agreement among raft nodes before linearized reading' (duration: 461.015901ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:31.137521Z","caller":"traceutil/trace.go:171","msg":"trace[1217440592] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:628; }","duration":"591.428903ms","start":"2026-04-07T09:42:30.54608Z","end":"2026-04-07T09:42:31.137509Z","steps":["trace[1217440592] 'agreement among raft nodes before linearized reading' (duration: 591.354871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:31.137563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:30.546064Z","time spent":"591.490785ms","remote":"127.0.0.1:34062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-07T09:42:31.137527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.505943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:42:31.137629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.241899ms","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":"warn","ts":"2026-04-07T09:42:31.13765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.133829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:42:31.137703Z","caller":"traceutil/trace.go:171","msg":"trace[140976348] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:628; }","duration":"462.203841ms","start":"2026-04-07T09:42:30.675476Z","end":"2026-04-07T09:42:31.13768Z","steps":["trace[140976348] 'agreement among raft nodes before linearized reading' (duration: 462.134829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:31.137753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:30.675464Z","time spent":"462.278352ms","remote":"127.0.0.1:34096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-07T09:42:31.137763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.799957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:42:31.137792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.268634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:42:31.137799Z","caller":"traceutil/trace.go:171","msg":"trace[1508461691] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:628; }","duration":"590.854639ms","start":"2026-04-07T09:42:30.546934Z","end":"2026-04-07T09:42:31.137789Z","steps":["trace[1508461691] 'agreement among raft nodes before linearized reading' (duration: 590.801428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:31.137767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.846849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:42:31.137832Z","caller":"traceutil/trace.go:171","msg":"trace[1017652207] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:628; }","duration":"314.308655ms","start":"2026-04-07T09:42:30.823514Z","end":"2026-04-07T09:42:31.137823Z","steps":["trace[1017652207] 'agreement among raft nodes before linearized reading' (duration: 313.613518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:31.137846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:30.546928Z","time spent":"590.90818ms","remote":"127.0.0.1:34062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 "} {"level":"warn","ts":"2026-04-07T09:42:31.137864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.89625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:42:31.137867Z","caller":"traceutil/trace.go:171","msg":"trace[873347395] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:628; }","duration":"313.032535ms","start":"2026-04-07T09:42:30.824811Z","end":"2026-04-07T09:42:31.137844Z","steps":["trace[873347395] 'agreement among raft nodes before linearized reading' (duration: 312.299217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:31.137881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:30.823496Z","time spent":"314.372947ms","remote":"127.0.0.1:34124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-07T09:42:31.13794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:30.824785Z","time spent":"313.137406ms","remote":"127.0.0.1:34124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 "} {"level":"warn","ts":"2026-04-07T09:42:31.137805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.392758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:42:31.13795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.37731ms","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-07T09:42:31.138031Z","caller":"traceutil/trace.go:171","msg":"trace[1156720878] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:628; }","duration":"643.631474ms","start":"2026-04-07T09:42:30.494384Z","end":"2026-04-07T09:42:31.138016Z","steps":["trace[1156720878] 'agreement among raft nodes before linearized reading' (duration: 643.391408ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:31.138066Z","caller":"traceutil/trace.go:171","msg":"trace[957786792] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:628; }","duration":"533.505083ms","start":"2026-04-07T09:42:30.604524Z","end":"2026-04-07T09:42:31.138029Z","steps":["trace[957786792] 'agreement among raft nodes before linearized reading' (duration: 532.671273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:31.138082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.241393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:42:31.138102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.288928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:42:31.13813Z","caller":"traceutil/trace.go:171","msg":"trace[1824453085] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:628; }","duration":"141.330315ms","start":"2026-04-07T09:42:30.996789Z","end":"2026-04-07T09:42:31.138119Z","steps":["trace[1824453085] 'agreement among raft nodes before linearized reading' (duration: 141.259863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:31.138133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.698832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:42:31.138133Z","caller":"traceutil/trace.go:171","msg":"trace[1422720480] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:628; }","duration":"346.32877ms","start":"2026-04-07T09:42:30.791796Z","end":"2026-04-07T09:42:31.138125Z","steps":["trace[1422720480] 'agreement among raft nodes before linearized reading' (duration: 345.339856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:31.138131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:30.604486Z","time spent":"533.632016ms","remote":"127.0.0.1:34078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-04-07T09:42:31.138166Z","caller":"traceutil/trace.go:171","msg":"trace[1046535429] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"522.755504ms","start":"2026-04-07T09:42:30.615401Z","end":"2026-04-07T09:42:31.138156Z","steps":["trace[1046535429] 'agreement among raft nodes before linearized reading' (duration: 522.707742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:31.138176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:30.791791Z","time spent":"346.374481ms","remote":"127.0.0.1:34108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-07T09:42:31.138199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:30.61539Z","time spent":"522.803345ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T09:42:31.138233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.419545ms","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":"warn","ts":"2026-04-07T09:42:31.137754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.112661ms","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-07T09:42:31.138277Z","caller":"traceutil/trace.go:171","msg":"trace[1545786813] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:628; }","duration":"644.488007ms","start":"2026-04-07T09:42:30.493778Z","end":"2026-04-07T09:42:31.138266Z","steps":["trace[1545786813] 'agreement among raft nodes before linearized reading' (duration: 643.251726ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:31.13829Z","caller":"traceutil/trace.go:171","msg":"trace[1915618353] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:628; }","duration":"314.695886ms","start":"2026-04-07T09:42:30.823584Z","end":"2026-04-07T09:42:31.13828Z","steps":["trace[1915618353] 'agreement among raft nodes before linearized reading' (duration: 313.535997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:31.138316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:30.493763Z","time spent":"644.542288ms","remote":"127.0.0.1:34046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:42:31.138329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:30.823556Z","time spent":"314.759547ms","remote":"127.0.0.1:34124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"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-07T09:42:31.138355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.715689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:42:31.138382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.046397ms","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-07T09:42:31.138399Z","caller":"traceutil/trace.go:171","msg":"trace[1107189481] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:628; }","duration":"190.769412ms","start":"2026-04-07T09:42:30.947621Z","end":"2026-04-07T09:42:31.13839Z","steps":["trace[1107189481] 'agreement among raft nodes before linearized reading' (duration: 189.425359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:31.138401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.014442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:42:31.138415Z","caller":"traceutil/trace.go:171","msg":"trace[1182889609] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:628; }","duration":"486.118248ms","start":"2026-04-07T09:42:30.652286Z","end":"2026-04-07T09:42:31.138404Z","steps":["trace[1182889609] 'agreement among raft nodes before linearized reading' (duration: 486.064717ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:31.138432Z","caller":"traceutil/trace.go:171","msg":"trace[23471115] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:628; }","duration":"533.064413ms","start":"2026-04-07T09:42:30.605358Z","end":"2026-04-07T09:42:31.138423Z","steps":["trace[23471115] 'agreement among raft nodes before linearized reading' (duration: 531.805532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:31.138454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:30.652267Z","time spent":"486.17804ms","remote":"127.0.0.1:34084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-07T09:42:31.138482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:30.60535Z","time spent":"533.106254ms","remote":"127.0.0.1:34078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 "} {"level":"warn","ts":"2026-04-07T09:42:31.138485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.022846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:42:31.137537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.365911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:42:31.138304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.032207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:42:31.138526Z","caller":"traceutil/trace.go:171","msg":"trace[2021323056] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:628; }","duration":"534.098238ms","start":"2026-04-07T09:42:30.604416Z","end":"2026-04-07T09:42:31.138514Z","steps":["trace[2021323056] 'agreement among raft nodes before linearized reading' (duration: 532.792015ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:31.138532Z","caller":"traceutil/trace.go:171","msg":"trace[71636508] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:628; }","duration":"592.418428ms","start":"2026-04-07T09:42:30.546102Z","end":"2026-04-07T09:42:31.13852Z","steps":["trace[71636508] 'agreement among raft nodes before linearized reading' (duration: 591.30863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:31.138561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:30.604405Z","time spent":"534.145769ms","remote":"127.0.0.1:34078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-07T09:42:31.138604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.326684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:42:31.138608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.621761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:42:31.137892Z","caller":"traceutil/trace.go:171","msg":"trace[1696906540] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:628; }","duration":"643.962571ms","start":"2026-04-07T09:42:30.49392Z","end":"2026-04-07T09:42:31.137882Z","steps":["trace[1696906540] 'agreement among raft nodes before linearized reading' (duration: 643.90541ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:31.138643Z","caller":"traceutil/trace.go:171","msg":"trace[1636374335] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:628; }","duration":"347.678332ms","start":"2026-04-07T09:42:30.790954Z","end":"2026-04-07T09:42:31.138633Z","steps":["trace[1636374335] 'agreement among raft nodes before linearized reading' (duration: 346.193966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:31.138663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:30.493878Z","time spent":"644.771202ms","remote":"127.0.0.1:34046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-07T09:42:31.138693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:30.790936Z","time spent":"347.740393ms","remote":"127.0.0.1:34108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:42:31.138766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.248351ms","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":"warn","ts":"2026-04-07T09:42:31.138429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.179187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T09:42:31.138635Z","caller":"traceutil/trace.go:171","msg":"trace[2076989541] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:628; }","duration":"191.384176ms","start":"2026-04-07T09:42:30.947243Z","end":"2026-04-07T09:42:31.138628Z","steps":["trace[2076989541] 'agreement among raft nodes before linearized reading' (duration: 189.811348ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:31.138919Z","caller":"traceutil/trace.go:171","msg":"trace[1077664364] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"387.645279ms","start":"2026-04-07T09:42:30.751218Z","end":"2026-04-07T09:42:31.138863Z","steps":["trace[1077664364] 'agreement among raft nodes before linearized reading' (duration: 385.983288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:31.139058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:30.751203Z","time spent":"387.839183ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-07T09:42:31.13913Z","caller":"traceutil/trace.go:171","msg":"trace[1226989295] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:628; }","duration":"486.663981ms","start":"2026-04-07T09:42:30.652455Z","end":"2026-04-07T09:42:31.139119Z","steps":["trace[1226989295] 'agreement among raft nodes before linearized reading' (duration: 485.79803ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:31.138562Z","caller":"traceutil/trace.go:171","msg":"trace[1350140523] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:628; }","duration":"191.354745ms","start":"2026-04-07T09:42:30.947194Z","end":"2026-04-07T09:42:31.138549Z","steps":["trace[1350140523] 'agreement among raft nodes before linearized reading' (duration: 189.867609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:31.139206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:30.652431Z","time spent":"486.726153ms","remote":"127.0.0.1:34084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-07T09:42:31.137699Z","caller":"traceutil/trace.go:171","msg":"trace[482143994] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:628; }","duration":"346.695827ms","start":"2026-04-07T09:42:30.790932Z","end":"2026-04-07T09:42:31.137628Z","steps":["trace[482143994] 'agreement among raft nodes before linearized reading' (duration: 346.255417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:31.138076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:30.494376Z","time spent":"643.688145ms","remote":"127.0.0.1:34046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-07T09:42:31.139429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:30.79092Z","time spent":"348.449151ms","remote":"127.0.0.1:34108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-07T09:42:31.138028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.524148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:42:31.138086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.119383ms","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-07T09:42:31.13857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:30.546089Z","time spent":"592.469909ms","remote":"127.0.0.1:34062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"info","ts":"2026-04-07T09:42:31.137704Z","caller":"traceutil/trace.go:171","msg":"trace[557127006] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:628; }","duration":"560.310711ms","start":"2026-04-07T09:42:30.577365Z","end":"2026-04-07T09:42:31.137676Z","steps":["trace[557127006] 'agreement among raft nodes before linearized reading' (duration: 560.237429ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:31.139641Z","caller":"traceutil/trace.go:171","msg":"trace[21261983] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:628; }","duration":"486.661562ms","start":"2026-04-07T09:42:30.652954Z","end":"2026-04-07T09:42:31.139616Z","steps":["trace[21261983] 'agreement among raft nodes before linearized reading' (duration: 485.103784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:31.137573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:30.676185Z","time spent":"461.36729ms","remote":"127.0.0.1:34096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-07T09:42:31.139687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:30.577358Z","time spent":"562.312479ms","remote":"127.0.0.1:34074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-04-07T09:42:31.139625Z","caller":"traceutil/trace.go:171","msg":"trace[469266680] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:628; }","duration":"464.138118ms","start":"2026-04-07T09:42:30.67547Z","end":"2026-04-07T09:42:31.139608Z","steps":["trace[469266680] 'agreement among raft nodes before linearized reading' (duration: 462.522708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:31.139683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:30.652934Z","time spent":"486.736293ms","remote":"127.0.0.1:34084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-07T09:42:31.139794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:30.675462Z","time spent":"464.312212ms","remote":"127.0.0.1:34096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:42:31.137417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:30.577685Z","time spent":"559.720477ms","remote":"127.0.0.1:34074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 "} {"level":"info","ts":"2026-04-07T09:42:46.726315Z","caller":"traceutil/trace.go:171","msg":"trace[1287238438] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:816; }","duration":"110.015309ms","start":"2026-04-07T09:42:46.616271Z","end":"2026-04-07T09:42:46.726286Z","steps":["trace[1287238438] 'read index received' (duration: 109.860445ms)","trace[1287238438] 'applied index is now lower than readState.Index' (duration: 153.594µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:42:46.726424Z","caller":"traceutil/trace.go:171","msg":"trace[1631754157] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"154.165486ms","start":"2026-04-07T09:42:46.572227Z","end":"2026-04-07T09:42:46.726393Z","steps":["trace[1631754157] 'process raft request' (duration: 153.872759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:46.72646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.172242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:42:46.726692Z","caller":"traceutil/trace.go:171","msg":"trace[421170346] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:789; }","duration":"110.454068ms","start":"2026-04-07T09:42:46.616196Z","end":"2026-04-07T09:42:46.726651Z","steps":["trace[421170346] 'agreement among raft nodes before linearized reading' (duration: 110.203772ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:13.163404Z","caller":"traceutil/trace.go:171","msg":"trace[1630301805] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"230.472913ms","start":"2026-04-07T09:44:12.932902Z","end":"2026-04-07T09:44:13.163375Z","steps":["trace[1630301805] 'process raft request' (duration: 230.303579ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:16.77098Z","caller":"traceutil/trace.go:171","msg":"trace[1681415816] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"109.762898ms","start":"2026-04-07T09:44:16.66118Z","end":"2026-04-07T09:44:16.770943Z","steps":["trace[1681415816] 'process raft request' (duration: 43.03504ms)","trace[1681415816] 'compare' (duration: 66.37874ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:44:16.959077Z","caller":"traceutil/trace.go:171","msg":"trace[638365239] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"112.153264ms","start":"2026-04-07T09:44:16.846886Z","end":"2026-04-07T09:44:16.959039Z","steps":["trace[638365239] 'process raft request' (duration: 111.97832ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:16.959133Z","caller":"traceutil/trace.go:171","msg":"trace[585533982] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"112.208445ms","start":"2026-04-07T09:44:16.846883Z","end":"2026-04-07T09:44:16.959091Z","steps":["trace[585533982] 'process raft request' (duration: 52.80503ms)","trace[585533982] 'compare' (duration: 58.7711ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:44:19.119289Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276101528144751490,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:19.619947Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276101528144751490,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:20.120908Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276101528144751490,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:20.621737Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276101528144751490,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:21.122872Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276101528144751490,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:21.616483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000394249s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-07T09:44:21.616571Z","caller":"traceutil/trace.go:171","msg":"trace[425742293] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000535763s","start":"2026-04-07T09:44:19.616018Z","end":"2026-04-07T09:44:21.616554Z","steps":["trace[425742293] 'agreement among raft nodes before linearized reading' (duration: 2.0004322s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:21.616606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:19.615993Z","time spent":"2.000606354s","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/07 09:44:21 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:21.623385Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276101528144751490,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:22.123781Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276101528144751490,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:22.623925Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276101528144751490,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:23.105184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:18.107259Z","time spent":"4.997644779s","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/04/07 09:44:23 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:23.12454Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276101528144751490,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:23.392301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:18.395096Z","time spent":"4.997196609s","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-07T09:44:23.616828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.99997816s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-07T09:44:23.616957Z","caller":"traceutil/trace.go:171","msg":"trace[1773859012] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000171324s","start":"2026-04-07T09:44:21.616765Z","end":"2026-04-07T09:44:23.616936Z","steps":["trace[1773859012] 'agreement among raft nodes before linearized reading' (duration: 2.00000152s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:23.617014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:21.61675Z","time spent":"2.000252596s","remote":"127.0.0.1:50870","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-07T09:44:23.625258Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276101528144751490,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:24.126244Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276101528144751490,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:24.42154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999506706s","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-07T09:44:24.421631Z","caller":"traceutil/trace.go:171","msg":"trace[2064522748] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.99965739s","start":"2026-04-07T09:44:19.421952Z","end":"2026-04-07T09:44:24.42161Z","steps":["trace[2064522748] 'agreement among raft nodes before linearized reading' (duration: 4.999547077s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:24.421681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:19.421929Z","time spent":"4.999742802s","remote":"127.0.0.1:51134","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/07 09:44:24 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:24.627026Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276101528144751490,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:24.697324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998925117s","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-07T09:44:24.697417Z","caller":"traceutil/trace.go:171","msg":"trace[1360562504] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.99906706s","start":"2026-04-07T09:44:19.698328Z","end":"2026-04-07T09:44:24.697395Z","steps":["trace[1360562504] 'agreement among raft nodes before linearized reading' (duration: 4.998948738s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:24.697472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:19.698313Z","time spent":"4.999148672s","remote":"127.0.0.1:51134","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/07 09:44:24 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:24.741995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998805242s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-07T09:44:24.742086Z","caller":"traceutil/trace.go:171","msg":"trace[1821311503] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.998940606s","start":"2026-04-07T09:44:19.743124Z","end":"2026-04-07T09:44:24.742064Z","steps":["trace[1821311503] 'agreement among raft nodes before linearized reading' (duration: 4.998828083s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:24.742142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:19.743111Z","time spent":"4.999014007s","remote":"127.0.0.1:51134","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/07 09:44:24 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:25.127706Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276101528144751490,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:25.618934Z","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-07T09:44:25.619134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.000771805s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-04-07T09:44:25.619197Z","caller":"traceutil/trace.go:171","msg":"trace[70745495] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"7.000895898s","start":"2026-04-07T09:44:18.618277Z","end":"2026-04-07T09:44:25.619173Z","steps":["trace[70745495] 'agreement among raft nodes before linearized reading' (duration: 7.000816455s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:25.619246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:18.61825Z","time spent":"7.000986301s","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T09:44:25.625055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000635962s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-07T09:44:25.625253Z","caller":"traceutil/trace.go:171","msg":"trace[534618003] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000878597s","start":"2026-04-07T09:44:23.624351Z","end":"2026-04-07T09:44:25.625229Z","steps":["trace[534618003] 'agreement among raft nodes before linearized reading' (duration: 2.000648892s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:25.625341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:23.624336Z","time spent":"2.00098948s","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/07 09:44:25 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:25.777834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:18.777123Z","time spent":"7.000699823s","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-07T09:44:26.101408Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"7.980985071s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T09:44:26.238579Z","caller":"traceutil/trace.go:171","msg":"trace[437662814] transaction","detail":"{read_only:false; response_revision:1940; number_of_response:1; }","duration":"6.103131107s","start":"2026-04-07T09:44:20.13541Z","end":"2026-04-07T09:44:26.238542Z","steps":["trace[437662814] 'process raft request' (duration: 6.102988003s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:26.238765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:20.135384Z","time spent":"6.103293001s","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:44:26.238784Z","caller":"traceutil/trace.go:171","msg":"trace[430245348] transaction","detail":"{read_only:false; response_revision:1941; number_of_response:1; }","duration":"5.359774699s","start":"2026-04-07T09:44:20.878927Z","end":"2026-04-07T09:44:26.238702Z","steps":["trace[430245348] 'process raft request' (duration: 5.359514163s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:26.239144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:20.878894Z","time spent":"5.360057806s","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:44:26.299391Z","caller":"traceutil/trace.go:171","msg":"trace[1385807385] transaction","detail":"{read_only:false; response_revision:1942; number_of_response:1; }","duration":"2.678728583s","start":"2026-04-07T09:44:23.620619Z","end":"2026-04-07T09:44:26.299348Z","steps":["trace[1385807385] 'process raft request' (duration: 2.678258621s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:26.299437Z","caller":"traceutil/trace.go:171","msg":"trace[58565068] transaction","detail":"{read_only:false; response_revision:1943; number_of_response:1; }","duration":"167.436188ms","start":"2026-04-07T09:44:26.131975Z","end":"2026-04-07T09:44:26.299411Z","steps":["trace[58565068] 'process raft request' (duration: 167.351666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:26.299647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:23.620595Z","time spent":"2.678921068s","remote":"127.0.0.1:50964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T09:44:26.299716Z","caller":"traceutil/trace.go:171","msg":"trace[1266409358] linearizableReadLoop","detail":"{readStateIndex:2020; appliedIndex:2013; }","duration":"680.626006ms","start":"2026-04-07T09:44:25.619061Z","end":"2026-04-07T09:44:26.299687Z","steps":["trace[1266409358] 'read index received' (duration: 482.57995ms)","trace[1266409358] 'applied index is now lower than readState.Index' (duration: 198.043806ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:44:26.30007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.451777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:44:26.300117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.946525293s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"warn","ts":"2026-04-07T09:44:26.300145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.367699989s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:44:26.300163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.109146462s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-04-07T09:44:26.300156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.307631219s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T09:44:26.3002Z","caller":"traceutil/trace.go:171","msg":"trace[1953732041] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1943; }","duration":"4.946665776s","start":"2026-04-07T09:44:21.353506Z","end":"2026-04-07T09:44:26.300172Z","steps":["trace[1953732041] 'agreement among raft nodes before linearized reading' (duration: 4.946338557s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:26.300198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.46905242s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-07T09:44:26.300228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.141813905s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:44:26.300292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.74496738s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:44:26.300309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.074038274s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:44:26.300258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.226600091s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:44:26.30034Z","caller":"traceutil/trace.go:171","msg":"trace[1774895731] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1943; }","duration":"2.074104676s","start":"2026-04-07T09:44:24.226226Z","end":"2026-04-07T09:44:26.300331Z","steps":["trace[1774895731] 'agreement among raft nodes before linearized reading' (duration: 2.074051105s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:26.30035Z","caller":"traceutil/trace.go:171","msg":"trace[1720052779] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1943; }","duration":"6.14201586s","start":"2026-04-07T09:44:20.158305Z","end":"2026-04-07T09:44:26.300321Z","steps":["trace[1720052779] 'agreement among raft nodes before linearized reading' (duration: 6.141753134s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:26.300387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:24.226207Z","time spent":"2.074170867s","remote":"127.0.0.1:34194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:44:26.300391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.905873319s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:549"} {"level":"info","ts":"2026-04-07T09:44:26.300159Z","caller":"traceutil/trace.go:171","msg":"trace[1390338866] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1943; }","duration":"674.599431ms","start":"2026-04-07T09:44:25.625545Z","end":"2026-04-07T09:44:26.300144Z","steps":["trace[1390338866] 'agreement among raft nodes before linearized reading' (duration: 674.464737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:26.300411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.803361537s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T09:44:26.300428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.514032651s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-07T09:44:26.300472Z","caller":"traceutil/trace.go:171","msg":"trace[1284691285] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:1943; }","duration":"5.803464349s","start":"2026-04-07T09:44:20.496994Z","end":"2026-04-07T09:44:26.300458Z","steps":["trace[1284691285] 'agreement among raft nodes before linearized reading' (duration: 5.803358677s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:26.300507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.712315792s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-04-07T09:44:26.300528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.193544325s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:523"} {"level":"info","ts":"2026-04-07T09:44:26.300388Z","caller":"traceutil/trace.go:171","msg":"trace[1654724277] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1943; }","duration":"3.226803336s","start":"2026-04-07T09:44:23.073556Z","end":"2026-04-07T09:44:26.300359Z","steps":["trace[1654724277] 'agreement among raft nodes before linearized reading' (duration: 3.226536979s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:26.300587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:23.073523Z","time spent":"3.227052251s","remote":"127.0.0.1:34138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"info","ts":"2026-04-07T09:44:26.300591Z","caller":"traceutil/trace.go:171","msg":"trace[1687891292] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:1943; }","duration":"3.193627377s","start":"2026-04-07T09:44:23.106936Z","end":"2026-04-07T09:44:26.300564Z","steps":["trace[1687891292] 'agreement among raft nodes before linearized reading' (duration: 3.19334597s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:26.300656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:23.106914Z","time spent":"3.193730019s","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":545,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-04-07T09:44:26.300523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:20.496972Z","time spent":"5.80353824s","remote":"127.0.0.1:33608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":29,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:44:26.300669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.184068ms","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-07T09:44:26.300808Z","caller":"traceutil/trace.go:171","msg":"trace[709150288] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1943; }","duration":"518.348762ms","start":"2026-04-07T09:44:25.782442Z","end":"2026-04-07T09:44:26.30079Z","steps":["trace[709150288] 'agreement among raft nodes before linearized reading' (duration: 517.86279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:26.300875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:25.782422Z","time spent":"518.440624ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":593,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-04-07T09:44:26.300218Z","caller":"traceutil/trace.go:171","msg":"trace[1141982932] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1943; }","duration":"6.367822572s","start":"2026-04-07T09:44:19.932382Z","end":"2026-04-07T09:44:26.300205Z","steps":["trace[1141982932] 'agreement among raft nodes before linearized reading' (duration: 6.367696269s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:26.301017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:19.932359Z","time spent":"6.368638291s","remote":"127.0.0.1:34076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-04-07T09:44:26.300586Z","caller":"traceutil/trace.go:171","msg":"trace[329411340] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1943; }","duration":"2.712420195s","start":"2026-04-07T09:44:23.588138Z","end":"2026-04-07T09:44:26.300558Z","steps":["trace[329411340] 'agreement among raft nodes before linearized reading' (duration: 2.712110178s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:26.301212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:23.58811Z","time spent":"2.713080812s","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-07T09:44:26.300467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:25.625506Z","time spent":"674.946639ms","remote":"127.0.0.1:50870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T09:44:26.300432Z","caller":"traceutil/trace.go:171","msg":"trace[1312366091] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:1943; }","duration":"2.905962001s","start":"2026-04-07T09:44:23.39446Z","end":"2026-04-07T09:44:26.300422Z","steps":["trace[1312366091] 'agreement among raft nodes before linearized reading' (duration: 2.905576952s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:26.301458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:23.394438Z","time spent":"2.906994925s","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":571,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-04-07T09:44:26.300232Z","caller":"traceutil/trace.go:171","msg":"trace[1209109045] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1943; }","duration":"7.109269195s","start":"2026-04-07T09:44:19.190946Z","end":"2026-04-07T09:44:26.300215Z","steps":["trace[1209109045] 'agreement among raft nodes before linearized reading' (duration: 7.109083451s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:26.300133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.690762146s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11665"} {"level":"warn","ts":"2026-04-07T09:44:26.301609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:19.190916Z","time spent":"7.110642837s","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":576,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-07T09:44:26.301667Z","caller":"traceutil/trace.go:171","msg":"trace[324212330] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1943; }","duration":"4.692322623s","start":"2026-04-07T09:44:21.609326Z","end":"2026-04-07T09:44:26.301649Z","steps":["trace[324212330] 'agreement among raft nodes before linearized reading' (duration: 4.690462778s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:26.300236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.766812698s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T09:44:26.301732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:21.60926Z","time spent":"4.692455187s","remote":"127.0.0.1:51048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11687,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-07T09:44:26.301773Z","caller":"traceutil/trace.go:171","msg":"trace[687182163] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:1943; }","duration":"3.768375745s","start":"2026-04-07T09:44:22.533379Z","end":"2026-04-07T09:44:26.301754Z","steps":["trace[687182163] 'agreement among raft nodes before linearized reading' (duration: 3.766817738s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:26.300236Z","caller":"traceutil/trace.go:171","msg":"trace[1477780167] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1943; }","duration":"6.307722031s","start":"2026-04-07T09:44:19.992501Z","end":"2026-04-07T09:44:26.300223Z","steps":["trace[1477780167] 'agreement among raft nodes before linearized reading' (duration: 6.307572878s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:26.302043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:19.992463Z","time spent":"6.309499613s","remote":"127.0.0.1:51202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":23,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:44:26.300209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.100562931s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T09:44:26.302131Z","caller":"traceutil/trace.go:171","msg":"trace[1551961638] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1943; }","duration":"7.102593749s","start":"2026-04-07T09:44:19.199518Z","end":"2026-04-07T09:44:26.302112Z","steps":["trace[1551961638] 'agreement among raft nodes before linearized reading' (duration: 7.100482609s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:26.302187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:19.1995Z","time spent":"7.102672301s","remote":"127.0.0.1:51014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":29,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:44:26.300277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:21.353483Z","time spent":"4.946773449s","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":551,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-04-07T09:44:26.300311Z","caller":"traceutil/trace.go:171","msg":"trace[1476033388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1943; }","duration":"6.469232454s","start":"2026-04-07T09:44:19.831053Z","end":"2026-04-07T09:44:26.300285Z","steps":["trace[1476033388] 'agreement among raft nodes before linearized reading' (duration: 6.468838385s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:26.302373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:19.831024Z","time spent":"6.471330134s","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-07T09:44:26.300333Z","caller":"traceutil/trace.go:171","msg":"trace[1481010374] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1943; }","duration":"2.745082112s","start":"2026-04-07T09:44:23.555241Z","end":"2026-04-07T09:44:26.300323Z","steps":["trace[1481010374] 'agreement among raft nodes before linearized reading' (duration: 2.7450186s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:26.302485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:23.555224Z","time spent":"2.747248514s","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:44:26.300416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:20.158281Z","time spent":"6.142122802s","remote":"127.0.0.1:34164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:44:26.300435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.859768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-07T09:44:26.302708Z","caller":"traceutil/trace.go:171","msg":"trace[389723848] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:1943; }","duration":"449.179434ms","start":"2026-04-07T09:44:25.853516Z","end":"2026-04-07T09:44:26.302695Z","steps":["trace[389723848] 'agreement among raft nodes before linearized reading' (duration: 446.758636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:26.302753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:25.853485Z","time spent":"449.256966ms","remote":"127.0.0.1:50916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.129\" "} {"level":"info","ts":"2026-04-07T09:44:26.30048Z","caller":"traceutil/trace.go:171","msg":"trace[1559473550] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1943; }","duration":"7.514119573s","start":"2026-04-07T09:44:18.786346Z","end":"2026-04-07T09:44:26.300466Z","steps":["trace[1559473550] 'agreement among raft nodes before linearized reading' (duration: 7.51355511s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:26.302926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:18.786326Z","time spent":"7.516562232s","remote":"127.0.0.1:50138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-07T09:44:26.301859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:22.53336Z","time spent":"3.768476038s","remote":"127.0.0.1:33630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":2,"response size":29,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:44:28.030482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.929558ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:44:28.030596Z","caller":"traceutil/trace.go:171","msg":"trace[753257103] transaction","detail":"{read_only:false; response_revision:1964; number_of_response:1; }","duration":"291.570652ms","start":"2026-04-07T09:44:27.739004Z","end":"2026-04-07T09:44:28.030574Z","steps":["trace[753257103] 'process raft request' (duration: 65.399698ms)","trace[753257103] 'compare' (duration: 225.735704ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:44:28.504965Z","caller":"traceutil/trace.go:171","msg":"trace[900747568] transaction","detail":"{read_only:false; response_revision:1969; number_of_response:1; }","duration":"134.198687ms","start":"2026-04-07T09:44:28.370735Z","end":"2026-04-07T09:44:28.504934Z","steps":["trace[900747568] 'process raft request' (duration: 81.341562ms)","trace[900747568] 'compare' (duration: 52.66746ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:44:28.505002Z","caller":"traceutil/trace.go:171","msg":"trace[823490015] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"130.225362ms","start":"2026-04-07T09:44:28.374737Z","end":"2026-04-07T09:44:28.504963Z","steps":["trace[823490015] 'process raft request' (duration: 130.121889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:35.01557Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276101528144751635,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:35.515938Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276101528144751635,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:36.016681Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276101528144751635,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:36.517652Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276101528144751635,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:36.615961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000020758s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-07T09:44:36.616096Z","caller":"traceutil/trace.go:171","msg":"trace[1189333096] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000212243s","start":"2026-04-07T09:44:34.615863Z","end":"2026-04-07T09:44:36.616075Z","steps":["trace[1189333096] 'agreement among raft nodes before linearized reading' (duration: 2.000053749s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:36.616181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:34.61584Z","time spent":"2.000325506s","remote":"127.0.0.1:50880","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-07T09:44:37.01815Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276101528144751635,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:37.519318Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276101528144751635,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:37.960201Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.58846726s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-07T09:44:38.454031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:34.736793Z","time spent":"3.717229156s","remote":"127.0.0.1:40738","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-07T09:44:38.454285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.090710684s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-07T09:44:38.45437Z","caller":"traceutil/trace.go:171","msg":"trace[1423963862] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2011; }","duration":"4.090849907s","start":"2026-04-07T09:44:34.3635Z","end":"2026-04-07T09:44:38.45435Z","steps":["trace[1423963862] 'range keys from in-memory index tree' (duration: 4.090452408s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:38.45442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:34.363477Z","time spent":"4.090933468s","remote":"127.0.0.1:50138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-07T09:44:38.455606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.981488ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:44:38.455744Z","caller":"traceutil/trace.go:171","msg":"trace[1458749581] transaction","detail":"{read_only:false; response_revision:2012; number_of_response:1; }","duration":"4.084115225s","start":"2026-04-07T09:44:34.371605Z","end":"2026-04-07T09:44:38.455721Z","steps":["trace[1458749581] 'process raft request' (duration: 3.588927711s)","trace[1458749581] 'compare' (duration: 494.302461ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:44:38.455812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:34.371588Z","time spent":"4.084193357s","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:44:38.616733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999870155s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-07T09:44:38.61682Z","caller":"traceutil/trace.go:171","msg":"trace[98256899] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000007818s","start":"2026-04-07T09:44:36.616792Z","end":"2026-04-07T09:44:38.6168Z","steps":["trace[98256899] 'agreement among raft nodes before linearized reading' (duration: 1.999900096s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:38.616869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:36.616773Z","time spent":"2.00008647s","remote":"127.0.0.1:50870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/07 09:44:38 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:39.428662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:34.430526Z","time spent":"4.9981287s","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/04/07 09:44:39 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:39.585795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999220446s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-07T09:44:39.585872Z","caller":"traceutil/trace.go:171","msg":"trace[626669280] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.999342668s","start":"2026-04-07T09:44:34.586512Z","end":"2026-04-07T09:44:39.585855Z","steps":["trace[626669280] 'agreement among raft nodes before linearized reading' (duration: 4.999245036s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:39.585919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:34.586499Z","time spent":"4.99941097s","remote":"127.0.0.1:51134","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\" "} {"level":"warn","ts":"2026-04-07T09:44:39.796021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999374129s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-07T09:44:39.79612Z","caller":"traceutil/trace.go:171","msg":"trace[442995530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999519633s","start":"2026-04-07T09:44:34.796574Z","end":"2026-04-07T09:44:39.796093Z","steps":["trace[442995530] 'agreement among raft nodes before linearized reading' (duration: 4.99940679s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:39.796263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:34.796544Z","time spent":"4.999689937s","remote":"127.0.0.1:51134","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\" "} {"level":"warn","ts":"2026-04-07T09:44:39.809666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999178425s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-07T09:44:39.80977Z","caller":"traceutil/trace.go:171","msg":"trace[1929493165] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999335429s","start":"2026-04-07T09:44:34.810411Z","end":"2026-04-07T09:44:39.809746Z","steps":["trace[1929493165] 'agreement among raft nodes before linearized reading' (duration: 4.999200936s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:39.809848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:34.810392Z","time spent":"4.999436152s","remote":"127.0.0.1:51134","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/07 09:44:39 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:40.268778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999977238s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-07T09:44:40.268875Z","caller":"traceutil/trace.go:171","msg":"trace[927707169] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000109931s","start":"2026-04-07T09:44:38.268733Z","end":"2026-04-07T09:44:40.268843Z","steps":["trace[927707169] 'agreement among raft nodes before linearized reading' (duration: 2.000011559s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:40.268918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:38.268711Z","time spent":"2.000198123s","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/07 09:44:40 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:40.624746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000118451s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-07T09:44:40.62482Z","caller":"traceutil/trace.go:171","msg":"trace[2052938165] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000256504s","start":"2026-04-07T09:44:38.62455Z","end":"2026-04-07T09:44:40.624806Z","steps":["trace[2052938165] 'agreement among raft nodes before linearized reading' (duration: 2.000156262s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:40.624851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:38.624528Z","time spent":"2.000317475s","remote":"127.0.0.1:50870","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-07T09:44:41.034145Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.073604552s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-07T09:44:41.524105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:34.523374Z","time spent":"7.00072016s","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-07T09:44:42.518956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.084147156s","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-07T09:44:42.51912Z","caller":"traceutil/trace.go:171","msg":"trace[59479166] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2012; }","duration":"3.084326041s","start":"2026-04-07T09:44:39.434769Z","end":"2026-04-07T09:44:42.519095Z","steps":["trace[59479166] 'range keys from in-memory index tree' (duration: 3.084125426s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:42.519885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.485358325s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:44:42.520392Z","caller":"traceutil/trace.go:171","msg":"trace[1933084682] transaction","detail":"{read_only:false; response_revision:2016; number_of_response:1; }","duration":"5.2979073s","start":"2026-04-07T09:44:37.222451Z","end":"2026-04-07T09:44:42.520359Z","steps":["trace[1933084682] 'process raft request' (duration: 5.297710565s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:42.520554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:37.222401Z","time spent":"5.298066514s","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:44:42.520644Z","caller":"traceutil/trace.go:171","msg":"trace[1193585816] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"2.058863318s","start":"2026-04-07T09:44:40.461746Z","end":"2026-04-07T09:44:42.520609Z","steps":["trace[1193585816] 'process raft request' (duration: 2.058466468s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:42.520641Z","caller":"traceutil/trace.go:171","msg":"trace[40403435] transaction","detail":"{read_only:false; response_revision:2018; number_of_response:1; }","duration":"1.246448913s","start":"2026-04-07T09:44:41.274163Z","end":"2026-04-07T09:44:42.520612Z","steps":["trace[40403435] 'process raft request' (duration: 1.246084464s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:42.520673Z","caller":"traceutil/trace.go:171","msg":"trace[1150646615] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"6.206364582s","start":"2026-04-07T09:44:36.314272Z","end":"2026-04-07T09:44:42.520623Z","steps":["trace[1150646615] 'process raft request' (duration: 6.2058488s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:42.52066Z","caller":"traceutil/trace.go:171","msg":"trace[802740484] linearizableReadLoop","detail":"{readStateIndex:2096; appliedIndex:2091; }","duration":"8.00528228s","start":"2026-04-07T09:44:34.515344Z","end":"2026-04-07T09:44:42.520626Z","steps":["trace[802740484] 'read index received' (duration: 3.445194135s)","trace[802740484] 'applied index is now lower than readState.Index' (duration: 4.560085515s)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:44:42.520774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:36.314229Z","time spent":"6.206491595s","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:44:42.5208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:40.461721Z","time spent":"2.058987311s","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:44:42.520816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:41.274141Z","time spent":"1.246572286s","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:44:42.629807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000080581s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-07T09:44:42.629906Z","caller":"traceutil/trace.go:171","msg":"trace[1119706793] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000210923s","start":"2026-04-07T09:44:40.629666Z","end":"2026-04-07T09:44:42.629877Z","steps":["trace[1119706793] 'agreement among raft nodes before linearized reading' (duration: 2.00009699s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:42.629948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:40.629648Z","time spent":"2.000293286s","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/07 09:44:42 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:43.001064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.759565ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-07T09:44:43.00122Z","caller":"traceutil/trace.go:171","msg":"trace[1754840048] linearizableReadLoop","detail":"{readStateIndex:2099; appliedIndex:2098; }","duration":"480.473558ms","start":"2026-04-07T09:44:42.520728Z","end":"2026-04-07T09:44:43.001201Z","steps":["trace[1754840048] 'read index received' (duration: 71.761µs)","trace[1754840048] 'applied index is now lower than readState.Index' (duration: 480.400267ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:44:43.001351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.861685526s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:44:43.001351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.147216254s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:44:43.001356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.570129535s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:44:43.001398Z","caller":"traceutil/trace.go:171","msg":"trace[2078559755] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:0; response_revision:2019; }","duration":"7.147301595s","start":"2026-04-07T09:44:35.854083Z","end":"2026-04-07T09:44:43.001384Z","steps":["trace[2078559755] 'agreement among raft nodes before linearized reading' (duration: 7.147198143s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.001435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.870471877s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:44:43.001448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:35.854068Z","time spent":"7.147371577s","remote":"127.0.0.1:50916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.129\" "} {"level":"warn","ts":"2026-04-07T09:44:43.001427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.317184113s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-07T09:44:43.001483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.473000177s","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-07T09:44:43.001526Z","caller":"traceutil/trace.go:171","msg":"trace[1316774226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2019; }","duration":"7.317312726s","start":"2026-04-07T09:44:35.684192Z","end":"2026-04-07T09:44:43.001504Z","steps":["trace[1316774226] 'agreement among raft nodes before linearized reading' (duration: 7.317100781s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:43.001553Z","caller":"traceutil/trace.go:171","msg":"trace[928948407] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2019; }","duration":"1.47311139s","start":"2026-04-07T09:44:41.528423Z","end":"2026-04-07T09:44:43.001534Z","steps":["trace[928948407] 'agreement among raft nodes before linearized reading' (duration: 1.472914715s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.001516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.991699752s","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":"warn","ts":"2026-04-07T09:44:43.001583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:35.68417Z","time spent":"7.317400919s","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T09:44:43.00139Z","caller":"traceutil/trace.go:171","msg":"trace[687968757] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:2019; }","duration":"7.861753058s","start":"2026-04-07T09:44:35.139624Z","end":"2026-04-07T09:44:43.001377Z","steps":["trace[687968757] 'agreement among raft nodes before linearized reading' (duration: 7.861674856s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.001608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:41.528391Z","time spent":"1.473204181s","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":593,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-04-07T09:44:43.00163Z","caller":"traceutil/trace.go:171","msg":"trace[982743774] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:2019; }","duration":"6.991833985s","start":"2026-04-07T09:44:36.00977Z","end":"2026-04-07T09:44:43.001604Z","steps":["trace[982743774] 'agreement among raft nodes before linearized reading' (duration: 6.99161113s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.001665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.632193496s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-04-07T09:44:43.001737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.467649952s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-04-07T09:44:43.00182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.571479911s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:549"} {"level":"info","ts":"2026-04-07T09:44:43.001857Z","caller":"traceutil/trace.go:171","msg":"trace[708445386] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2019; }","duration":"8.467782765s","start":"2026-04-07T09:44:34.534045Z","end":"2026-04-07T09:44:43.001828Z","steps":["trace[708445386] 'agreement among raft nodes before linearized reading' (duration: 8.467578859s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:43.00189Z","caller":"traceutil/trace.go:171","msg":"trace[1561379144] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:2019; }","duration":"3.571582104s","start":"2026-04-07T09:44:39.430288Z","end":"2026-04-07T09:44:43.00187Z","steps":["trace[1561379144] 'agreement among raft nodes before linearized reading' (duration: 3.5714452s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.001903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.413784099s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-07T09:44:43.001907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.241849677s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:44:43.001919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:34.534032Z","time spent":"8.467872497s","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-07T09:44:43.001942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.203138099s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-07T09:44:43.001945Z","caller":"traceutil/trace.go:171","msg":"trace[1422679112] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2019; }","duration":"1.413871722s","start":"2026-04-07T09:44:41.58806Z","end":"2026-04-07T09:44:43.001932Z","steps":["trace[1422679112] 'agreement among raft nodes before linearized reading' (duration: 1.413761819s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:43.00147Z","caller":"traceutil/trace.go:171","msg":"trace[1576053476] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:2019; }","duration":"7.870549779s","start":"2026-04-07T09:44:35.130911Z","end":"2026-04-07T09:44:43.001461Z","steps":["trace[1576053476] 'agreement among raft nodes before linearized reading' (duration: 7.870497348s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.001953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:39.430213Z","time spent":"3.571726727s","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":571,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-04-07T09:44:43.002002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:41.588027Z","time spent":"1.413968084s","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-07T09:44:43.002Z","caller":"traceutil/trace.go:171","msg":"trace[191157061] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2019; }","duration":"1.203228391s","start":"2026-04-07T09:44:41.798759Z","end":"2026-04-07T09:44:43.001987Z","steps":["trace[191157061] 'agreement among raft nodes before linearized reading' (duration: 1.203089108s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.002023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:35.130879Z","time spent":"7.871127394s","remote":"127.0.0.1:34160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:44:43.002043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.944826316s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:44:43.002053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.739452143s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T09:44:43.002057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:41.798739Z","time spent":"1.203307534s","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T09:44:43.002056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.896927733s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:44:43.002081Z","caller":"traceutil/trace.go:171","msg":"trace[174261933] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:2019; }","duration":"3.944899728s","start":"2026-04-07T09:44:39.057167Z","end":"2026-04-07T09:44:43.002067Z","steps":["trace[174261933] 'agreement among raft nodes before linearized reading' (duration: 3.944842177s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:43.002101Z","caller":"traceutil/trace.go:171","msg":"trace[389836018] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:2019; }","duration":"2.739531325s","start":"2026-04-07T09:44:40.262554Z","end":"2026-04-07T09:44:43.002085Z","steps":["trace[389836018] 'agreement among raft nodes before linearized reading' (duration: 2.739445223s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.001592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.465589671s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-07T09:44:43.001638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:35.139614Z","time spent":"7.862013344s","remote":"127.0.0.1:34222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:44:43.001982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.381659883s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18a409ec4e16e87d\" ","response":"range_response_count:1 size:774"} {"level":"warn","ts":"2026-04-07T09:44:43.00215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.773767577s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:44:43.002159Z","caller":"traceutil/trace.go:171","msg":"trace[24099563] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:2019; }","duration":"1.897113837s","start":"2026-04-07T09:44:41.105028Z","end":"2026-04-07T09:44:43.002142Z","steps":["trace[24099563] 'agreement among raft nodes before linearized reading' (duration: 1.896977024s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:43.00219Z","caller":"traceutil/trace.go:171","msg":"trace[481384967] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18a409ec4e16e87d; range_end:; response_count:1; response_revision:2019; }","duration":"4.381897848s","start":"2026-04-07T09:44:38.620277Z","end":"2026-04-07T09:44:43.002175Z","steps":["trace[481384967] 'agreement among raft nodes before linearized reading' (duration: 4.38156699s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.001939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.190056084s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T09:44:43.002205Z","caller":"traceutil/trace.go:171","msg":"trace[1239203211] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:2019; }","duration":"5.773848069s","start":"2026-04-07T09:44:37.228336Z","end":"2026-04-07T09:44:43.002185Z","steps":["trace[1239203211] 'agreement among raft nodes before linearized reading' (duration: 5.773777937s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.002214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.755730476s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:44:43.002214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.549168989s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11982"} {"level":"info","ts":"2026-04-07T09:44:43.002243Z","caller":"traceutil/trace.go:171","msg":"trace[1427530870] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2019; }","duration":"1.190613547s","start":"2026-04-07T09:44:41.811616Z","end":"2026-04-07T09:44:43.002229Z","steps":["trace[1427530870] 'agreement among raft nodes before linearized reading' (duration: 1.190195007s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:43.002254Z","caller":"traceutil/trace.go:171","msg":"trace[536736756] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:2019; }","duration":"2.755810248s","start":"2026-04-07T09:44:40.246435Z","end":"2026-04-07T09:44:43.002245Z","steps":["trace[536736756] 'agreement among raft nodes before linearized reading' (duration: 2.755752856s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:43.002266Z","caller":"traceutil/trace.go:171","msg":"trace[1021039392] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2019; }","duration":"6.549252532s","start":"2026-04-07T09:44:36.453003Z","end":"2026-04-07T09:44:43.002256Z","steps":["trace[1021039392] 'agreement among raft nodes before linearized reading' (duration: 6.549098238s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.002304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:40.246413Z","time spent":"2.75588385s","remote":"127.0.0.1:34108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:44:43.002049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.107075341s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T09:44:43.002358Z","caller":"traceutil/trace.go:171","msg":"trace[1165322535] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2019; }","duration":"2.107387029s","start":"2026-04-07T09:44:40.894957Z","end":"2026-04-07T09:44:43.002344Z","steps":["trace[1165322535] 'agreement among raft nodes before linearized reading' (duration: 2.107036731s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.001691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:36.009745Z","time spent":"6.991934507s","remote":"127.0.0.1:34046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:44:43.002418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:40.894866Z","time spent":"2.107535732s","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-04-07T09:44:43.001768Z","caller":"traceutil/trace.go:171","msg":"trace[429081327] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2019; }","duration":"1.632324401s","start":"2026-04-07T09:44:41.369417Z","end":"2026-04-07T09:44:43.001742Z","steps":["trace[429081327] 'agreement among raft nodes before linearized reading' (duration: 1.632082644s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.002165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:40.262531Z","time spent":"2.739621467s","remote":"127.0.0.1:51118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:44:43.002557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:41.369398Z","time spent":"1.633143s","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-07T09:44:43.002308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:36.452988Z","time spent":"6.549313423s","remote":"127.0.0.1:51048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12004,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-07T09:44:43.002213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:41.104999Z","time spent":"1.897198669s","remote":"127.0.0.1:51258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-04-07T09:44:43.001458Z","caller":"traceutil/trace.go:171","msg":"trace[647062711] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:2019; }","duration":"6.570291609s","start":"2026-04-07T09:44:36.431137Z","end":"2026-04-07T09:44:43.001428Z","steps":["trace[647062711] 'agreement among raft nodes before linearized reading' (duration: 6.570105965s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:43.002167Z","caller":"traceutil/trace.go:171","msg":"trace[442443167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2019; }","duration":"8.466181755s","start":"2026-04-07T09:44:34.535969Z","end":"2026-04-07T09:44:43.002151Z","steps":["trace[442443167] 'agreement among raft nodes before linearized reading' (duration: 8.465520679s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.002822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:36.431118Z","time spent":"6.571678203s","remote":"127.0.0.1:51176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:44:43.002847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:34.535954Z","time spent":"8.466875562s","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T09:44:43.001421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.272649263s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:44:43.002916Z","caller":"traceutil/trace.go:171","msg":"trace[1254537118] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2019; }","duration":"3.274209421s","start":"2026-04-07T09:44:39.72868Z","end":"2026-04-07T09:44:43.00289Z","steps":["trace[1254537118] 'agreement among raft nodes before linearized reading' (duration: 3.272582361s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.001578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.458848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:44:43.001427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.486060675s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"warn","ts":"2026-04-07T09:44:43.002975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:39.728654Z","time spent":"3.274304763s","remote":"127.0.0.1:34144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-04-07T09:44:43.002989Z","caller":"traceutil/trace.go:171","msg":"trace[1622043756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2019; }","duration":"372.907384ms","start":"2026-04-07T09:44:42.630065Z","end":"2026-04-07T09:44:43.002972Z","steps":["trace[1622043756] 'agreement among raft nodes before linearized reading' (duration: 371.450608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.003046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:42.630042Z","time spent":"372.989016ms","remote":"127.0.0.1:50870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T09:44:43.00304Z","caller":"traceutil/trace.go:171","msg":"trace[1430714948] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2019; }","duration":"8.487744396s","start":"2026-04-07T09:44:34.515266Z","end":"2026-04-07T09:44:43.003011Z","steps":["trace[1430714948] 'agreement among raft nodes before linearized reading' (duration: 8.005456343s)","trace[1430714948] 'range keys from in-memory index tree' (duration: 480.478288ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:44:43.001965Z","caller":"traceutil/trace.go:171","msg":"trace[858032868] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:2019; }","duration":"7.241932079s","start":"2026-04-07T09:44:35.760018Z","end":"2026-04-07T09:44:43.00195Z","steps":["trace[858032868] 'agreement among raft nodes before linearized reading' (duration: 7.241839256s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.001963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.632499455s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-04-07T09:44:43.003161Z","caller":"traceutil/trace.go:171","msg":"trace[1576583113] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2019; }","duration":"1.633733454s","start":"2026-04-07T09:44:41.369417Z","end":"2026-04-07T09:44:43.003151Z","steps":["trace[1576583113] 'agreement among raft nodes before linearized reading' (duration: 1.632447013s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.003206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:41.369398Z","time spent":"1.633798605s","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":551,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-04-07T09:44:43.001696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.536741452s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-04-07T09:44:43.002232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:38.620258Z","time spent":"4.38196417s","remote":"127.0.0.1:50964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":796,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18a409ec4e16e87d\" "} {"level":"info","ts":"2026-04-07T09:44:43.003278Z","caller":"traceutil/trace.go:171","msg":"trace[1861370979] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2019; }","duration":"3.538340842s","start":"2026-04-07T09:44:39.46492Z","end":"2026-04-07T09:44:43.00326Z","steps":["trace[1861370979] 'agreement among raft nodes before linearized reading' (duration: 3.536577149s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.003164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:35.760003Z","time spent":"7.243148678s","remote":"127.0.0.1:51082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:44:43.003338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:39.464906Z","time spent":"3.538415613s","remote":"127.0.0.1:50138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-07T09:44:43.00312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:34.515239Z","time spent":"8.487861269s","remote":"127.0.0.1:51022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1875,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-07T09:44:43.002125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:39.057144Z","time spent":"3.944972939s","remote":"127.0.0.1:34118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:44:43.002252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:37.22832Z","time spent":"5.773918941s","remote":"127.0.0.1:33634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:44:43.002304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:41.81158Z","time spent":"1.190707329s","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T09:44:43.273223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.634922ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:44:43.273653Z","caller":"traceutil/trace.go:171","msg":"trace[1171513475] transaction","detail":"{read_only:false; response_revision:2022; number_of_response:1; }","duration":"265.085254ms","start":"2026-04-07T09:44:43.008544Z","end":"2026-04-07T09:44:43.27363Z","steps":["trace[1171513475] 'process raft request' (duration: 264.977982ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:43.273657Z","caller":"traceutil/trace.go:171","msg":"trace[445411282] transaction","detail":"{read_only:false; response_revision:2020; number_of_response:1; }","duration":"265.569005ms","start":"2026-04-07T09:44:43.008067Z","end":"2026-04-07T09:44:43.273636Z","steps":["trace[445411282] 'process raft request' (duration: 163.358349ms)","trace[445411282] 'compare' (duration: 101.393656ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:44:43.273677Z","caller":"traceutil/trace.go:171","msg":"trace[1817650462] transaction","detail":"{read_only:false; response_revision:2021; number_of_response:1; }","duration":"265.211827ms","start":"2026-04-07T09:44:43.008437Z","end":"2026-04-07T09:44:43.273649Z","steps":["trace[1817650462] 'process raft request' (duration: 264.95227ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:43.273894Z","caller":"traceutil/trace.go:171","msg":"trace[1455284471] transaction","detail":"{read_only:false; response_revision:2023; number_of_response:1; }","duration":"265.277238ms","start":"2026-04-07T09:44:43.008605Z","end":"2026-04-07T09:44:43.273882Z","steps":["trace[1455284471] 'process raft request' (duration: 264.973341ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:43.274214Z","caller":"traceutil/trace.go:171","msg":"trace[2067357972] transaction","detail":"{read_only:false; response_revision:2026; number_of_response:1; }","duration":"265.053843ms","start":"2026-04-07T09:44:43.009142Z","end":"2026-04-07T09:44:43.274196Z","steps":["trace[2067357972] 'process raft request' (duration: 264.999651ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:43.27422Z","caller":"traceutil/trace.go:171","msg":"trace[655696156] transaction","detail":"{read_only:false; response_revision:2024; number_of_response:1; }","duration":"265.482054ms","start":"2026-04-07T09:44:43.008707Z","end":"2026-04-07T09:44:43.274189Z","steps":["trace[655696156] 'process raft request' (duration: 265.127875ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:43.274341Z","caller":"traceutil/trace.go:171","msg":"trace[904331094] linearizableReadLoop","detail":"{readStateIndex:2109; appliedIndex:2100; }","duration":"207.216978ms","start":"2026-04-07T09:44:43.067107Z","end":"2026-04-07T09:44:43.274324Z","steps":["trace[904331094] 'read index received' (duration: 104.455359ms)","trace[904331094] 'applied index is now lower than readState.Index' (duration: 102.760329ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:44:43.27438Z","caller":"traceutil/trace.go:171","msg":"trace[853407213] transaction","detail":"{read_only:false; response_revision:2025; number_of_response:1; }","duration":"265.357771ms","start":"2026-04-07T09:44:43.009008Z","end":"2026-04-07T09:44:43.274366Z","steps":["trace[853407213] 'process raft request' (duration: 265.052123ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:43.27449Z","caller":"traceutil/trace.go:171","msg":"trace[286801355] transaction","detail":"{read_only:false; response_revision:2027; number_of_response:1; }","duration":"264.998761ms","start":"2026-04-07T09:44:43.00948Z","end":"2026-04-07T09:44:43.274478Z","steps":["trace[286801355] 'process raft request' (duration: 264.687924ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:43.274537Z","caller":"traceutil/trace.go:171","msg":"trace[1700747002] transaction","detail":"{read_only:false; response_revision:2028; number_of_response:1; }","duration":"264.863809ms","start":"2026-04-07T09:44:43.009662Z","end":"2026-04-07T09:44:43.274526Z","steps":["trace[1700747002] 'process raft request' (duration: 264.592392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.274565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.987319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-5bb27086-b35f-477b-bf99-1297c7f1c516\" ","response":"range_response_count:1 size:1303"} {"level":"info","ts":"2026-04-07T09:44:43.274747Z","caller":"traceutil/trace.go:171","msg":"trace[1139890478] range","detail":"{range_begin:/registry/persistentvolumes/pvc-5bb27086-b35f-477b-bf99-1297c7f1c516; range_end:; response_count:1; response_revision:2028; }","duration":"267.215794ms","start":"2026-04-07T09:44:43.007518Z","end":"2026-04-07T09:44:43.274733Z","steps":["trace[1139890478] 'agreement among raft nodes before linearized reading' (duration: 266.885497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.27449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.527912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:44:43.274843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.022237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:44:43.274888Z","caller":"traceutil/trace.go:171","msg":"trace[364319331] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2028; }","duration":"264.094569ms","start":"2026-04-07T09:44:43.010782Z","end":"2026-04-07T09:44:43.274877Z","steps":["trace[364319331] 'agreement among raft nodes before linearized reading' (duration: 264.000407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.275196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.919788ms","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":"warn","ts":"2026-04-07T09:44:43.275306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.875712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:549"} {"level":"info","ts":"2026-04-07T09:44:43.275507Z","caller":"traceutil/trace.go:171","msg":"trace[1754430491] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:2028; }","duration":"266.086558ms","start":"2026-04-07T09:44:43.00937Z","end":"2026-04-07T09:44:43.275457Z","steps":["trace[1754430491] 'agreement among raft nodes before linearized reading' (duration: 265.254268ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:43.274846Z","caller":"traceutil/trace.go:171","msg":"trace[415439859] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2028; }","duration":"262.905271ms","start":"2026-04-07T09:44:43.011925Z","end":"2026-04-07T09:44:43.27483Z","steps":["trace[415439859] 'agreement among raft nodes before linearized reading' (duration: 262.475001ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:43.275394Z","caller":"traceutil/trace.go:171","msg":"trace[1405910837] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2028; }","duration":"267.284126ms","start":"2026-04-07T09:44:43.008082Z","end":"2026-04-07T09:44:43.275366Z","steps":["trace[1405910837] 'agreement among raft nodes before linearized reading' (duration: 266.691262ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:43.360529Z","caller":"traceutil/trace.go:171","msg":"trace[308505472] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"283.907128ms","start":"2026-04-07T09:44:43.076599Z","end":"2026-04-07T09:44:43.360506Z","steps":["trace[308505472] 'process raft request' (duration: 283.830047ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:43.360538Z","caller":"traceutil/trace.go:171","msg":"trace[362577410] transaction","detail":"{read_only:false; response_revision:2029; number_of_response:1; }","duration":"292.190728ms","start":"2026-04-07T09:44:43.068309Z","end":"2026-04-07T09:44:43.3605Z","steps":["trace[362577410] 'process raft request' (duration: 291.956653ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:43.460985Z","caller":"traceutil/trace.go:171","msg":"trace[287657902] linearizableReadLoop","detail":"{readStateIndex:2112; appliedIndex:2109; }","duration":"181.691561ms","start":"2026-04-07T09:44:43.279231Z","end":"2026-04-07T09:44:43.460922Z","steps":["trace[287657902] 'read index received' (duration: 81.174198ms)","trace[287657902] 'applied index is now lower than readState.Index' (duration: 100.515973ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:44:43.46101Z","caller":"traceutil/trace.go:171","msg":"trace[1819085003] transaction","detail":"{read_only:false; response_revision:2031; number_of_response:1; }","duration":"182.317887ms","start":"2026-04-07T09:44:43.278617Z","end":"2026-04-07T09:44:43.460935Z","steps":["trace[1819085003] 'process raft request' (duration: 114.614724ms)","trace[1819085003] 'compare' (duration: 67.549479ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:44:43.461777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.525652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:44:43.461852Z","caller":"traceutil/trace.go:171","msg":"trace[606905107] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2031; }","duration":"182.638525ms","start":"2026-04-07T09:44:43.279195Z","end":"2026-04-07T09:44:43.461834Z","steps":["trace[606905107] 'agreement among raft nodes before linearized reading' (duration: 181.823455ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:45:19.927416Z","caller":"traceutil/trace.go:171","msg":"trace[1069837621] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"507.07182ms","start":"2026-04-07T09:45:19.420314Z","end":"2026-04-07T09:45:19.927386Z","steps":["trace[1069837621] 'process raft request' (duration: 506.852135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:45:19.927625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:45:19.42029Z","time spent":"507.232633ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:45:20.161138Z","caller":"traceutil/trace.go:171","msg":"trace[1893123253] linearizableReadLoop","detail":"{readStateIndex:2316; appliedIndex:2314; }","duration":"710.202849ms","start":"2026-04-07T09:45:19.450907Z","end":"2026-04-07T09:45:20.16111Z","steps":["trace[1893123253] 'read index received' (duration: 476.256757ms)","trace[1893123253] 'applied index is now lower than readState.Index' (duration: 233.944952ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:45:20.161557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.621989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-07T09:45:20.161723Z","caller":"traceutil/trace.go:171","msg":"trace[1361205471] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2227; }","duration":"710.840895ms","start":"2026-04-07T09:45:19.450839Z","end":"2026-04-07T09:45:20.16168Z","steps":["trace[1361205471] 'agreement among raft nodes before linearized reading' (duration: 710.397784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:45:20.161804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:45:19.450821Z","time spent":"710.968367ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T09:45:20.161894Z","caller":"traceutil/trace.go:171","msg":"trace[223620008] transaction","detail":"{read_only:false; response_revision:2227; number_of_response:1; }","duration":"736.253597ms","start":"2026-04-07T09:45:19.42562Z","end":"2026-04-07T09:45:20.161873Z","steps":["trace[223620008] 'process raft request' (duration: 725.163241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:45:20.162034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:45:19.425594Z","time spent":"736.350629ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:45:20.167957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.973595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T09:45:20.167971Z","caller":"traceutil/trace.go:171","msg":"trace[1604331696] transaction","detail":"{read_only:false; response_revision:2228; number_of_response:1; }","duration":"106.307404ms","start":"2026-04-07T09:45:20.061633Z","end":"2026-04-07T09:45:20.167941Z","steps":["trace[1604331696] 'process raft request' (duration: 106.011007ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:45:20.168025Z","caller":"traceutil/trace.go:171","msg":"trace[1217455797] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2228; }","duration":"708.071498ms","start":"2026-04-07T09:45:19.459937Z","end":"2026-04-07T09:45:20.168008Z","steps":["trace[1217455797] 'agreement among raft nodes before linearized reading' (duration: 707.877103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:45:20.168015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.619863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:45:20.168065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:45:19.459923Z","time spent":"708.13407ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T09:45:20.16803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.844501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-07T09:45:20.168044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.368552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-07T09:45:20.168086Z","caller":"traceutil/trace.go:171","msg":"trace[1819693398] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2228; }","duration":"553.732126ms","start":"2026-04-07T09:45:19.614327Z","end":"2026-04-07T09:45:20.16806Z","steps":["trace[1819693398] 'agreement among raft nodes before linearized reading' (duration: 553.634244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:45:20.168138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:45:19.614305Z","time spent":"553.819687ms","remote":"127.0.0.1:50880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T09:45:20.168133Z","caller":"traceutil/trace.go:171","msg":"trace[94964169] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2228; }","duration":"700.482144ms","start":"2026-04-07T09:45:19.467633Z","end":"2026-04-07T09:45:20.168115Z","steps":["trace[94964169] 'agreement among raft nodes before linearized reading' (duration: 700.199617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:45:20.168199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:45:19.467627Z","time spent":"700.558826ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-07T09:45:20.168202Z","caller":"traceutil/trace.go:171","msg":"trace[2130747315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2228; }","duration":"704.954033ms","start":"2026-04-07T09:45:19.463147Z","end":"2026-04-07T09:45:20.168101Z","steps":["trace[2130747315] 'agreement among raft nodes before linearized reading' (duration: 704.736548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:45:20.168283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.968207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-04-07T09:45:20.168375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:45:19.463136Z","time spent":"705.223589ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T09:45:20.168407Z","caller":"traceutil/trace.go:171","msg":"trace[856002134] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2228; }","duration":"706.135371ms","start":"2026-04-07T09:45:19.462242Z","end":"2026-04-07T09:45:20.168378Z","steps":["trace[856002134] 'agreement among raft nodes before linearized reading' (duration: 705.762283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:45:20.16849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:45:19.462231Z","time spent":"706.245684ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-07T09:45:20.16853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.851171ms","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-07T09:45:20.168688Z","caller":"traceutil/trace.go:171","msg":"trace[2051889344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2228; }","duration":"705.080746ms","start":"2026-04-07T09:45:19.463589Z","end":"2026-04-07T09:45:20.16867Z","steps":["trace[2051889344] 'agreement among raft nodes before linearized reading' (duration: 704.472712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:45:20.168765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:45:19.463566Z","time spent":"705.152808ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T09:45:20.720454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.606179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:45:20.720686Z","caller":"traceutil/trace.go:171","msg":"trace[1224775747] transaction","detail":"{read_only:false; response_revision:2232; number_of_response:1; }","duration":"547.177326ms","start":"2026-04-07T09:45:20.17349Z","end":"2026-04-07T09:45:20.720668Z","steps":["trace[1224775747] 'process raft request' (duration: 547.070154ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:45:20.720913Z","caller":"traceutil/trace.go:171","msg":"trace[679156825] transaction","detail":"{read_only:false; response_revision:2231; number_of_response:1; }","duration":"547.676158ms","start":"2026-04-07T09:45:20.173194Z","end":"2026-04-07T09:45:20.72087Z","steps":["trace[679156825] 'process raft request' (duration: 363.533177ms)","trace[679156825] 'compare' (duration: 183.320021ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:45:20.72097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:45:20.173466Z","time spent":"547.428832ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:45:20.72116Z","caller":"traceutil/trace.go:171","msg":"trace[1167144644] transaction","detail":"{read_only:false; response_revision:2233; number_of_response:1; }","duration":"547.507174ms","start":"2026-04-07T09:45:20.173625Z","end":"2026-04-07T09:45:20.721132Z","steps":["trace[1167144644] 'process raft request' (duration: 547.007732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:45:20.721174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:45:20.173176Z","time spent":"547.846982ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:45:20.7213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:45:20.173607Z","time spent":"547.606256ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:45:20.721387Z","caller":"traceutil/trace.go:171","msg":"trace[1765178561] transaction","detail":"{read_only:false; response_revision:2234; number_of_response:1; }","duration":"547.602456ms","start":"2026-04-07T09:45:20.173771Z","end":"2026-04-07T09:45:20.721374Z","steps":["trace[1765178561] 'process raft request' (duration: 547.271028ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:45:20.721403Z","caller":"traceutil/trace.go:171","msg":"trace[340544649] linearizableReadLoop","detail":"{readStateIndex:2323; appliedIndex:2319; }","duration":"417.656792ms","start":"2026-04-07T09:45:20.303718Z","end":"2026-04-07T09:45:20.721374Z","steps":["trace[340544649] 'read index received' (duration: 233.06924ms)","trace[340544649] 'applied index is now lower than readState.Index' (duration: 184.585722ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:45:20.721453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:45:20.173735Z","time spent":"547.683198ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:45:20.721584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.805127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:45:20.721601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.881248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-07T09:45:20.721648Z","caller":"traceutil/trace.go:171","msg":"trace[468779440] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2234; }","duration":"104.903159ms","start":"2026-04-07T09:45:20.616727Z","end":"2026-04-07T09:45:20.72163Z","steps":["trace[468779440] 'agreement among raft nodes before linearized reading' (duration: 104.804177ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:45:20.721661Z","caller":"traceutil/trace.go:171","msg":"trace[2018379102] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2234; }","duration":"417.991391ms","start":"2026-04-07T09:45:20.303659Z","end":"2026-04-07T09:45:20.721651Z","steps":["trace[2018379102] 'agreement among raft nodes before linearized reading' (duration: 417.790196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:45:20.721781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:45:20.303631Z","time spent":"418.133904ms","remote":"127.0.0.1:50138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-07T09:46:18.592452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.495068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-07T09:46:18.592565Z","caller":"traceutil/trace.go:171","msg":"trace[1265671981] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2707; }","duration":"108.667842ms","start":"2026-04-07T09:46:18.483874Z","end":"2026-04-07T09:46:18.592542Z","steps":["trace[1265671981] 'range keys from in-memory index tree' (duration: 108.247933ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:46:22.547919Z","caller":"traceutil/trace.go:171","msg":"trace[1704033876] transaction","detail":"{read_only:false; response_revision:2729; number_of_response:1; }","duration":"288.475217ms","start":"2026-04-07T09:46:22.259424Z","end":"2026-04-07T09:46:22.547899Z","steps":["trace[1704033876] 'process raft request' (duration: 287.999335ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:46:22.570457Z","caller":"traceutil/trace.go:171","msg":"trace[185734847] transaction","detail":"{read_only:false; response_revision:2730; number_of_response:1; }","duration":"114.057342ms","start":"2026-04-07T09:46:22.456374Z","end":"2026-04-07T09:46:22.570432Z","steps":["trace[185734847] 'process raft request' (duration: 113.930599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:46:54.750934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.848131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:46:54.75108Z","caller":"traceutil/trace.go:171","msg":"trace[655862898] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2925; }","duration":"134.062626ms","start":"2026-04-07T09:46:54.61699Z","end":"2026-04-07T09:46:54.751053Z","steps":["trace[655862898] 'range keys from in-memory index tree' (duration: 133.687407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:46:55.27641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.458527ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:46:55.276687Z","caller":"traceutil/trace.go:171","msg":"trace[139628825] transaction","detail":"{read_only:false; response_revision:2930; number_of_response:1; }","duration":"126.166804ms","start":"2026-04-07T09:46:55.150501Z","end":"2026-04-07T09:46:55.276668Z","steps":["trace[139628825] 'process raft request' (duration: 126.055351ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:46:55.276632Z","caller":"traceutil/trace.go:171","msg":"trace[1150753494] transaction","detail":"{read_only:false; response_revision:2929; number_of_response:1; }","duration":"391.945479ms","start":"2026-04-07T09:46:54.884642Z","end":"2026-04-07T09:46:55.276588Z","steps":["trace[1150753494] 'process raft request' (duration: 216.172474ms)","trace[1150753494] 'compare' (duration: 175.097449ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:46:55.276847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:46:54.884617Z","time spent":"392.124132ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:46:55.276887Z","caller":"traceutil/trace.go:171","msg":"trace[349038601] linearizableReadLoop","detail":"{readStateIndex:3043; appliedIndex:3042; }","duration":"330.655415ms","start":"2026-04-07T09:46:54.946223Z","end":"2026-04-07T09:46:55.276878Z","steps":["trace[349038601] 'read index received' (duration: 154.505691ms)","trace[349038601] 'applied index is now lower than readState.Index' (duration: 176.148524ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:46:55.277068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.850139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:46:55.277126Z","caller":"traceutil/trace.go:171","msg":"trace[1969786354] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:2930; }","duration":"330.912521ms","start":"2026-04-07T09:46:54.946204Z","end":"2026-04-07T09:46:55.277117Z","steps":["trace[1969786354] 'agreement among raft nodes before linearized reading' (duration: 330.822528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:46:55.277149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:46:54.946179Z","time spent":"330.963523ms","remote":"127.0.0.1:50154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true "} {"level":"info","ts":"2026-04-07T09:50:59.64886Z","caller":"traceutil/trace.go:171","msg":"trace[1741509085] transaction","detail":"{read_only:false; response_revision:4930; number_of_response:1; }","duration":"112.834241ms","start":"2026-04-07T09:50:59.535796Z","end":"2026-04-07T09:50:59.64863Z","steps":["trace[1741509085] 'process raft request' (duration: 71.944414ms)","trace[1741509085] 'compare' (duration: 40.472097ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:50:59.649004Z","caller":"traceutil/trace.go:171","msg":"trace[235777070] linearizableReadLoop","detail":"{readStateIndex:5122; appliedIndex:5121; }","duration":"110.507033ms","start":"2026-04-07T09:50:59.538462Z","end":"2026-04-07T09:50:59.648969Z","steps":["trace[235777070] 'read index received' (duration: 69.288308ms)","trace[235777070] 'applied index is now lower than readState.Index' (duration: 41.215805ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:50:59.649514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.824972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-07T09:50:59.649654Z","caller":"traceutil/trace.go:171","msg":"trace[397867381] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4931; }","duration":"110.994605ms","start":"2026-04-07T09:50:59.538631Z","end":"2026-04-07T09:50:59.649626Z","steps":["trace[397867381] 'agreement among raft nodes before linearized reading' (duration: 110.606876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:50:59.649567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.070368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:10 size:76033"} {"level":"info","ts":"2026-04-07T09:50:59.649836Z","caller":"traceutil/trace.go:171","msg":"trace[1563192864] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:10; response_revision:4931; }","duration":"111.408537ms","start":"2026-04-07T09:50:59.538411Z","end":"2026-04-07T09:50:59.64982Z","steps":["trace[1563192864] 'agreement among raft nodes before linearized reading' (duration: 110.74999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:51:10.25365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.955957ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:51:10.253909Z","caller":"traceutil/trace.go:171","msg":"trace[2127386726] transaction","detail":"{read_only:false; response_revision:5208; number_of_response:1; }","duration":"414.125671ms","start":"2026-04-07T09:51:09.839759Z","end":"2026-04-07T09:51:10.253885Z","steps":["trace[2127386726] 'process raft request' (duration: 119.801504ms)","trace[2127386726] 'compare' (duration: 293.604849ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:51:10.253991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:51:09.839725Z","time spent":"414.230482ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:51:10.254249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.802713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-29483fb2-4135-4a5a-bd8a-a4aa141e0dc6\" ","response":"range_response_count:1 size:3721"} {"level":"info","ts":"2026-04-07T09:51:10.254364Z","caller":"traceutil/trace.go:171","msg":"trace[635956078] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-29483fb2-4135-4a5a-bd8a-a4aa141e0dc6; range_end:; response_count:1; response_revision:5207; }","duration":"454.971617ms","start":"2026-04-07T09:51:09.799369Z","end":"2026-04-07T09:51:10.25434Z","steps":["trace[635956078] 'range keys from in-memory index tree' (duration: 454.514976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:51:10.254419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:51:09.799349Z","time spent":"455.060589ms","remote":"127.0.0.1:51048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3743,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-29483fb2-4135-4a5a-bd8a-a4aa141e0dc6\" "} {"level":"info","ts":"2026-04-07T09:51:10.254593Z","caller":"traceutil/trace.go:171","msg":"trace[1499735615] transaction","detail":"{read_only:false; response_revision:5209; number_of_response:1; }","duration":"407.956868ms","start":"2026-04-07T09:51:09.84662Z","end":"2026-04-07T09:51:10.254577Z","steps":["trace[1499735615] 'process raft request' (duration: 407.172519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:51:10.254681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:51:09.846603Z","time spent":"408.039721ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:51:10.841981Z","caller":"traceutil/trace.go:171","msg":"trace[933331642] transaction","detail":"{read_only:false; response_revision:5218; number_of_response:1; }","duration":"173.107999ms","start":"2026-04-07T09:51:10.668838Z","end":"2026-04-07T09:51:10.841946Z","steps":["trace[933331642] 'process raft request' (duration: 173.053017ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:51:10.842042Z","caller":"traceutil/trace.go:171","msg":"trace[89735550] transaction","detail":"{read_only:false; response_revision:5217; number_of_response:1; }","duration":"173.236212ms","start":"2026-04-07T09:51:10.668758Z","end":"2026-04-07T09:51:10.841994Z","steps":["trace[89735550] 'process raft request' (duration: 173.083818ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:51:10.842521Z","caller":"traceutil/trace.go:171","msg":"trace[478419383] transaction","detail":"{read_only:false; response_revision:5216; number_of_response:1; }","duration":"174.947024ms","start":"2026-04-07T09:51:10.667519Z","end":"2026-04-07T09:51:10.842466Z","steps":["trace[478419383] 'process raft request' (duration: 135.669716ms)","trace[478419383] 'compare' (duration: 38.477119ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:51:20.920142Z","caller":"traceutil/trace.go:171","msg":"trace[1359312615] transaction","detail":"{read_only:false; response_revision:5409; number_of_response:1; }","duration":"125.583566ms","start":"2026-04-07T09:51:20.794531Z","end":"2026-04-07T09:51:20.920114Z","steps":["trace[1359312615] 'process raft request' (duration: 125.521264ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:51:20.920198Z","caller":"traceutil/trace.go:171","msg":"trace[1528578940] transaction","detail":"{read_only:false; response_revision:5408; number_of_response:1; }","duration":"129.191595ms","start":"2026-04-07T09:51:20.790984Z","end":"2026-04-07T09:51:20.920175Z","steps":["trace[1528578940] 'process raft request' (duration: 124.042159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:51:21.309668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.000992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-07T09:51:21.309818Z","caller":"traceutil/trace.go:171","msg":"trace[1726610262] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5419; }","duration":"110.227047ms","start":"2026-04-07T09:51:21.19955Z","end":"2026-04-07T09:51:21.309777Z","steps":["trace[1726610262] 'range keys from in-memory index tree' (duration: 109.741546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:51:29.312156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.087767ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:51:29.312317Z","caller":"traceutil/trace.go:171","msg":"trace[1141507447] linearizableReadLoop","detail":"{readStateIndex:5804; appliedIndex:5803; }","duration":"305.346518ms","start":"2026-04-07T09:51:29.006948Z","end":"2026-04-07T09:51:29.312294Z","steps":["trace[1141507447] 'read index received' (duration: 157.902523ms)","trace[1141507447] 'applied index is now lower than readState.Index' (duration: 147.442105ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:51:29.312343Z","caller":"traceutil/trace.go:171","msg":"trace[138021319] transaction","detail":"{read_only:false; response_revision:5592; number_of_response:1; }","duration":"334.409645ms","start":"2026-04-07T09:51:28.977896Z","end":"2026-04-07T09:51:29.312305Z","steps":["trace[138021319] 'process raft request' (duration: 187.042041ms)","trace[138021319] 'compare' (duration: 146.835541ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:51:29.312549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:51:28.97786Z","time spent":"334.583999ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:51:29.312561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.626036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-07T09:51:29.312604Z","caller":"traceutil/trace.go:171","msg":"trace[397998686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5592; }","duration":"305.719717ms","start":"2026-04-07T09:51:29.006873Z","end":"2026-04-07T09:51:29.312592Z","steps":["trace[397998686] 'agreement among raft nodes before linearized reading' (duration: 305.512532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:51:29.312643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:51:29.006847Z","time spent":"305.784888ms","remote":"127.0.0.1:51134","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-07T09:51:29.79156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.808896ms","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-07T09:51:29.791736Z","caller":"traceutil/trace.go:171","msg":"trace[1178117146] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5592; }","duration":"357.008611ms","start":"2026-04-07T09:51:29.434702Z","end":"2026-04-07T09:51:29.79171Z","steps":["trace[1178117146] 'range keys from in-memory index tree' (duration: 356.773416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:51:29.791853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.462414ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:51:29.792029Z","caller":"traceutil/trace.go:171","msg":"trace[1744147109] transaction","detail":"{read_only:false; response_revision:5593; number_of_response:1; }","duration":"558.268125ms","start":"2026-04-07T09:51:29.233744Z","end":"2026-04-07T09:51:29.792012Z","steps":["trace[1744147109] 'process raft request' (duration: 206.517963ms)","trace[1744147109] 'compare' (duration: 351.25652ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:51:29.792116Z","caller":"traceutil/trace.go:171","msg":"trace[840870439] transaction","detail":"{read_only:false; response_revision:5594; number_of_response:1; }","duration":"473.222757ms","start":"2026-04-07T09:51:29.318854Z","end":"2026-04-07T09:51:29.792077Z","steps":["trace[840870439] 'process raft request' (duration: 473.144705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:51:29.792161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:51:29.233701Z","time spent":"558.366857ms","remote":"127.0.0.1:51042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19052,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:51:29.792142Z","caller":"traceutil/trace.go:171","msg":"trace[1934491289] linearizableReadLoop","detail":"{readStateIndex:5805; appliedIndex:5804; }","duration":"479.748878ms","start":"2026-04-07T09:51:29.312346Z","end":"2026-04-07T09:51:29.792095Z","steps":["trace[1934491289] 'read index received' (duration: 127.928294ms)","trace[1934491289] 'applied index is now lower than readState.Index' (duration: 351.816423ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:51:29.792254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:51:29.31882Z","time spent":"473.361131ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:51:29.792578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.231998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T09:51:29.792593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.718627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-07T09:51:29.792617Z","caller":"traceutil/trace.go:171","msg":"trace[1849600998] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:5594; }","duration":"705.33097ms","start":"2026-04-07T09:51:29.087274Z","end":"2026-04-07T09:51:29.792605Z","steps":["trace[1849600998] 'agreement among raft nodes before linearized reading' (duration: 705.250347ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:51:29.792646Z","caller":"traceutil/trace.go:171","msg":"trace[563249151] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5594; }","duration":"599.846811ms","start":"2026-04-07T09:51:29.192785Z","end":"2026-04-07T09:51:29.792631Z","steps":["trace[563249151] 'agreement among raft nodes before linearized reading' (duration: 599.541732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:51:29.792659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:51:29.087241Z","time spent":"705.409512ms","remote":"127.0.0.1:51160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":4,"response size":29,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:51:29.792688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:51:29.192756Z","time spent":"599.923052ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T09:51:29.792679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.493585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-07T09:51:29.792711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.909036ms","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":"warn","ts":"2026-04-07T09:51:29.792744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.6702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T09:51:29.792767Z","caller":"traceutil/trace.go:171","msg":"trace[627366250] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5594; }","duration":"720.626568ms","start":"2026-04-07T09:51:29.07212Z","end":"2026-04-07T09:51:29.792747Z","steps":["trace[627366250] 'agreement among raft nodes before linearized reading' (duration: 720.33291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:51:29.792788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.112182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:51:29.79283Z","caller":"traceutil/trace.go:171","msg":"trace[1937032953] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5594; }","duration":"429.08665ms","start":"2026-04-07T09:51:29.363713Z","end":"2026-04-07T09:51:29.792799Z","steps":["trace[1937032953] 'agreement among raft nodes before linearized reading' (duration: 428.785531ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:51:29.792911Z","caller":"traceutil/trace.go:171","msg":"trace[1848211630] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5594; }","duration":"176.307667ms","start":"2026-04-07T09:51:29.616572Z","end":"2026-04-07T09:51:29.792879Z","steps":["trace[1848211630] 'agreement among raft nodes before linearized reading' (duration: 176.058361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:51:29.792918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:51:29.363692Z","time spent":"429.209433ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-07T09:51:29.792837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:51:29.072105Z","time spent":"720.7225ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T09:51:29.792748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.133889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7aa796cf-780e-47fd-835f-7927507ea64b\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-07T09:51:29.793119Z","caller":"traceutil/trace.go:171","msg":"trace[1497352347] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7aa796cf-780e-47fd-835f-7927507ea64b; range_end:; response_count:1; response_revision:5594; }","duration":"680.530099ms","start":"2026-04-07T09:51:29.112565Z","end":"2026-04-07T09:51:29.793095Z","steps":["trace[1497352347] 'agreement among raft nodes before linearized reading' (duration: 679.953964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:51:29.793209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:51:29.112549Z","time spent":"680.639211ms","remote":"127.0.0.1:51048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7aa796cf-780e-47fd-835f-7927507ea64b\" "} {"level":"info","ts":"2026-04-07T09:51:29.792829Z","caller":"traceutil/trace.go:171","msg":"trace[64962639] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:5594; }","duration":"321.817424ms","start":"2026-04-07T09:51:29.470993Z","end":"2026-04-07T09:51:29.792811Z","steps":["trace[64962639] 'agreement among raft nodes before linearized reading' (duration: 321.6392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:51:29.793286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:51:29.47097Z","time spent":"322.296966ms","remote":"127.0.0.1:51014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":5,"response size":29,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:51:32.107711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.410022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-04-07T09:51:32.107804Z","caller":"traceutil/trace.go:171","msg":"trace[702665582] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5621; }","duration":"140.587456ms","start":"2026-04-07T09:51:31.967196Z","end":"2026-04-07T09:51:32.107784Z","steps":["trace[702665582] 'range keys from in-memory index tree' (duration: 140.170706ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:51:52.628212Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2915} {"level":"info","ts":"2026-04-07T09:51:52.704388Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2915,"took":"74.107987ms","hash":4082726133,"current-db-size-bytes":55398400,"current-db-size":"55 MB","current-db-size-in-use-bytes":31989760,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-07T09:51:52.704456Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4082726133,"revision":2915,"compact-revision":-1} {"level":"info","ts":"2026-04-07T09:56:52.636132Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5832} {"level":"info","ts":"2026-04-07T09:56:52.718782Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5832,"took":"80.325819ms","hash":2464189462,"current-db-size-bytes":55398400,"current-db-size":"55 MB","current-db-size-in-use-bytes":25763840,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-07T09:56:52.718834Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2464189462,"revision":5832,"compact-revision":2915} {"level":"warn","ts":"2026-04-07T09:57:35.056163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.453511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T09:57:35.056269Z","caller":"traceutil/trace.go:171","msg":"trace[1330214001] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:8423; }","duration":"194.626345ms","start":"2026-04-07T09:57:34.861622Z","end":"2026-04-07T09:57:35.056249Z","steps":["trace[1330214001] 'count revisions from in-memory index tree' (duration: 194.326858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:57:48.184065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.80063ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:57:48.184438Z","caller":"traceutil/trace.go:171","msg":"trace[969976243] linearizableReadLoop","detail":"{readStateIndex:8796; appliedIndex:8795; }","duration":"270.961546ms","start":"2026-04-07T09:57:47.913408Z","end":"2026-04-07T09:57:48.184369Z","steps":["trace[969976243] 'read index received' (duration: 84.364807ms)","trace[969976243] 'applied index is now lower than readState.Index' (duration: 186.594669ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:57:48.184455Z","caller":"traceutil/trace.go:171","msg":"trace[1314369932] transaction","detail":"{read_only:false; response_revision:8490; number_of_response:1; }","duration":"278.988281ms","start":"2026-04-07T09:57:47.905399Z","end":"2026-04-07T09:57:48.184387Z","steps":["trace[1314369932] 'process raft request' (duration: 92.512095ms)","trace[1314369932] 'compare' (duration: 185.433451ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:57:48.184774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.326064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-07T09:57:48.184904Z","caller":"traceutil/trace.go:171","msg":"trace[1569263759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8490; }","duration":"271.54581ms","start":"2026-04-07T09:57:47.913326Z","end":"2026-04-07T09:57:48.184872Z","steps":["trace[1569263759] 'agreement among raft nodes before linearized reading' (duration: 271.159741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:57:48.184946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.687692ms","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-07T09:57:48.185011Z","caller":"traceutil/trace.go:171","msg":"trace[2064419805] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8490; }","duration":"191.808035ms","start":"2026-04-07T09:57:47.993189Z","end":"2026-04-07T09:57:48.184997Z","steps":["trace[2064419805] 'agreement among raft nodes before linearized reading' (duration: 191.59704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:57:48.185148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.118568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-07T09:57:48.185259Z","caller":"traceutil/trace.go:171","msg":"trace[74761633] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8490; }","duration":"145.271432ms","start":"2026-04-07T09:57:48.039965Z","end":"2026-04-07T09:57:48.185236Z","steps":["trace[74761633] 'agreement among raft nodes before linearized reading' (duration: 144.808481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:57:48.185464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.190388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T09:57:48.185625Z","caller":"traceutil/trace.go:171","msg":"trace[1733365973] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8490; }","duration":"186.381552ms","start":"2026-04-07T09:57:47.999214Z","end":"2026-04-07T09:57:48.185596Z","steps":["trace[1733365973] 'agreement among raft nodes before linearized reading' (duration: 185.914081ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:57:48.458291Z","caller":"traceutil/trace.go:171","msg":"trace[93812271] transaction","detail":"{read_only:false; response_revision:8491; number_of_response:1; }","duration":"268.789872ms","start":"2026-04-07T09:57:48.189469Z","end":"2026-04-07T09:57:48.458259Z","steps":["trace[93812271] 'process raft request' (duration: 257.62058ms)","trace[93812271] 'compare' (duration: 11.052709ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:57:48.490577Z","caller":"traceutil/trace.go:171","msg":"trace[956186443] transaction","detail":"{read_only:false; response_revision:8492; number_of_response:1; }","duration":"299.806648ms","start":"2026-04-07T09:57:48.190724Z","end":"2026-04-07T09:57:48.49053Z","steps":["trace[956186443] 'process raft request' (duration: 299.432669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:57:48.490859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:57:48.190683Z","time spent":"300.040624ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:57:48.491444Z","caller":"traceutil/trace.go:171","msg":"trace[1338983358] transaction","detail":"{read_only:false; response_revision:8493; number_of_response:1; }","duration":"300.666871ms","start":"2026-04-07T09:57:48.190752Z","end":"2026-04-07T09:57:48.491419Z","steps":["trace[1338983358] 'process raft request' (duration: 299.616564ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:57:48.491413Z","caller":"traceutil/trace.go:171","msg":"trace[1021217579] linearizableReadLoop","detail":"{readStateIndex:8800; appliedIndex:8796; }","duration":"222.242339ms","start":"2026-04-07T09:57:48.269141Z","end":"2026-04-07T09:57:48.491383Z","steps":["trace[1021217579] 'read index received' (duration: 177.920188ms)","trace[1021217579] 'applied index is now lower than readState.Index' (duration: 44.320841ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:57:48.491619Z","caller":"traceutil/trace.go:171","msg":"trace[984904841] transaction","detail":"{read_only:false; response_revision:8494; number_of_response:1; }","duration":"300.492316ms","start":"2026-04-07T09:57:48.191114Z","end":"2026-04-07T09:57:48.491606Z","steps":["trace[984904841] 'process raft request' (duration: 300.194509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:57:48.491631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:57:48.190725Z","time spent":"300.797653ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:57:48.491709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:57:48.191099Z","time spent":"300.557367ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:57:48.491764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.719494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:57:48.491824Z","caller":"traceutil/trace.go:171","msg":"trace[1444387018] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:8494; }","duration":"175.827707ms","start":"2026-04-07T09:57:48.315981Z","end":"2026-04-07T09:57:48.491809Z","steps":["trace[1444387018] 'agreement among raft nodes before linearized reading' (duration: 175.724154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:57:48.49201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.853633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:57:48.492123Z","caller":"traceutil/trace.go:171","msg":"trace[149141457] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8494; }","duration":"223.063498ms","start":"2026-04-07T09:57:48.269032Z","end":"2026-04-07T09:57:48.492096Z","steps":["trace[149141457] 'agreement among raft nodes before linearized reading' (duration: 222.778611ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:57:50.081894Z","caller":"traceutil/trace.go:171","msg":"trace[97779207] transaction","detail":"{read_only:false; response_revision:8498; number_of_response:1; }","duration":"182.392926ms","start":"2026-04-07T09:57:49.899465Z","end":"2026-04-07T09:57:50.081858Z","steps":["trace[97779207] 'process raft request' (duration: 182.211011ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:57:50.082654Z","caller":"traceutil/trace.go:171","msg":"trace[1795074444] transaction","detail":"{read_only:false; response_revision:8500; number_of_response:1; }","duration":"170.820055ms","start":"2026-04-07T09:57:49.911786Z","end":"2026-04-07T09:57:50.082606Z","steps":["trace[1795074444] 'process raft request' (duration: 170.763673ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:57:50.083231Z","caller":"traceutil/trace.go:171","msg":"trace[2118632235] transaction","detail":"{read_only:false; response_revision:8499; number_of_response:1; }","duration":"177.040865ms","start":"2026-04-07T09:57:49.906173Z","end":"2026-04-07T09:57:50.083213Z","steps":["trace[2118632235] 'process raft request' (duration: 176.206614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:58:14.282963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.515624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/rook-ceph/rook-ceph-csi-config\" ","response":"range_response_count:1 size:512"} {"level":"info","ts":"2026-04-07T09:58:14.283065Z","caller":"traceutil/trace.go:171","msg":"trace[1538072938] range","detail":"{range_begin:/registry/configmaps/rook-ceph/rook-ceph-csi-config; range_end:; response_count:1; response_revision:8684; }","duration":"103.674417ms","start":"2026-04-07T09:58:14.179366Z","end":"2026-04-07T09:58:14.283041Z","steps":["trace[1538072938] 'range keys from in-memory index tree' (duration: 103.326729ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:58:14.283146Z","caller":"traceutil/trace.go:171","msg":"trace[209533190] transaction","detail":"{read_only:false; response_revision:8685; number_of_response:1; }","duration":"101.956745ms","start":"2026-04-07T09:58:14.181152Z","end":"2026-04-07T09:58:14.283109Z","steps":["trace[209533190] 'process raft request' (duration: 98.503351ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:58:14.283194Z","caller":"traceutil/trace.go:171","msg":"trace[1445397333] transaction","detail":"{read_only:false; response_revision:8686; number_of_response:1; }","duration":"101.34439ms","start":"2026-04-07T09:58:14.181821Z","end":"2026-04-07T09:58:14.283165Z","steps":["trace[1445397333] 'process raft request' (duration: 101.197267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:58:16.737307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.952713ms","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-07T09:58:16.737432Z","caller":"traceutil/trace.go:171","msg":"trace[545695696] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:8696; }","duration":"627.151267ms","start":"2026-04-07T09:58:16.110253Z","end":"2026-04-07T09:58:16.737404Z","steps":["trace[545695696] 'count revisions from in-memory index tree' (duration: 626.86134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:58:16.73749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:58:16.110228Z","time spent":"627.24633ms","remote":"127.0.0.1:34272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:58:16.737878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.305104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:58:16.73833Z","caller":"traceutil/trace.go:171","msg":"trace[586795087] transaction","detail":"{read_only:false; response_revision:8697; number_of_response:1; }","duration":"449.120009ms","start":"2026-04-07T09:58:16.288919Z","end":"2026-04-07T09:58:16.738039Z","steps":["trace[586795087] 'process raft request' (duration: 202.523058ms)","trace[586795087] 'compare' (duration: 245.950074ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:58:16.73864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:58:16.288893Z","time spent":"449.495318ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:58:16.765049Z","caller":"traceutil/trace.go:171","msg":"trace[1047117777] transaction","detail":"{read_only:false; response_revision:8698; number_of_response:1; }","duration":"470.758436ms","start":"2026-04-07T09:58:16.294246Z","end":"2026-04-07T09:58:16.765005Z","steps":["trace[1047117777] 'process raft request' (duration: 470.452998ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:58:16.765214Z","caller":"traceutil/trace.go:171","msg":"trace[955844696] linearizableReadLoop","detail":"{readStateIndex:9013; appliedIndex:9011; }","duration":"161.517137ms","start":"2026-04-07T09:58:16.603667Z","end":"2026-04-07T09:58:16.765184Z","steps":["trace[955844696] 'read index received' (duration: 161.013605ms)","trace[955844696] 'applied index is now lower than readState.Index' (duration: 501.502µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:58:16.765382Z","caller":"traceutil/trace.go:171","msg":"trace[1500812755] transaction","detail":"{read_only:false; response_revision:8699; number_of_response:1; }","duration":"470.259603ms","start":"2026-04-07T09:58:16.294958Z","end":"2026-04-07T09:58:16.765218Z","steps":["trace[1500812755] 'process raft request' (duration: 469.972536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:58:16.765505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.932515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:58:16.765604Z","caller":"traceutil/trace.go:171","msg":"trace[2084023348] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8699; }","duration":"150.076579ms","start":"2026-04-07T09:58:16.615504Z","end":"2026-04-07T09:58:16.76558Z","steps":["trace[2084023348] 'agreement among raft nodes before linearized reading' (duration: 149.879734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:58:16.765598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.546315ms","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":"warn","ts":"2026-04-07T09:58:16.76561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.89751ms","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-07T09:58:16.765658Z","caller":"traceutil/trace.go:171","msg":"trace[662806084] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8699; }","duration":"117.678539ms","start":"2026-04-07T09:58:16.647967Z","end":"2026-04-07T09:58:16.765646Z","steps":["trace[662806084] 'agreement among raft nodes before linearized reading' (duration: 117.463213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:58:16.765629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.922387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T09:58:16.765917Z","caller":"traceutil/trace.go:171","msg":"trace[594269558] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8699; }","duration":"162.277216ms","start":"2026-04-07T09:58:16.603612Z","end":"2026-04-07T09:58:16.76589Z","steps":["trace[594269558] 'agreement among raft nodes before linearized reading' (duration: 161.695941ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:58:16.765696Z","caller":"traceutil/trace.go:171","msg":"trace[1165926928] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8699; }","duration":"115.054076ms","start":"2026-04-07T09:58:16.650621Z","end":"2026-04-07T09:58:16.765675Z","steps":["trace[1165926928] 'agreement among raft nodes before linearized reading' (duration: 114.782008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:58:16.765518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:58:16.294212Z","time spent":"470.987161ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:58:16.765602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:58:16.294946Z","time spent":"470.510029ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:58:16.765827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.03446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-07T09:58:16.766251Z","caller":"traceutil/trace.go:171","msg":"trace[720598067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8699; }","duration":"130.501672ms","start":"2026-04-07T09:58:16.635728Z","end":"2026-04-07T09:58:16.76623Z","steps":["trace[720598067] 'agreement among raft nodes before linearized reading' (duration: 130.02174ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:58:30.783869Z","caller":"traceutil/trace.go:171","msg":"trace[570531402] transaction","detail":"{read_only:false; response_revision:8814; number_of_response:1; }","duration":"214.198848ms","start":"2026-04-07T09:58:30.569637Z","end":"2026-04-07T09:58:30.783835Z","steps":["trace[570531402] 'process raft request' (duration: 213.958241ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:58:30.83706Z","caller":"traceutil/trace.go:171","msg":"trace[2116601827] transaction","detail":"{read_only:false; response_revision:8815; number_of_response:1; }","duration":"243.621464ms","start":"2026-04-07T09:58:30.593403Z","end":"2026-04-07T09:58:30.837025Z","steps":["trace[2116601827] 'process raft request' (duration: 220.659673ms)","trace[2116601827] 'compare' (duration: 22.616262ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:58:31.61214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.771142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-282h2\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-07T09:58:31.612245Z","caller":"traceutil/trace.go:171","msg":"trace[590287200] range","detail":"{range_begin:/registry/pods/openstack/keepalived-282h2; range_end:; response_count:1; response_revision:8826; }","duration":"187.948756ms","start":"2026-04-07T09:58:31.42427Z","end":"2026-04-07T09:58:31.612218Z","steps":["trace[590287200] 'range keys from in-memory index tree' (duration: 187.500176ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:58:44.122599Z","caller":"traceutil/trace.go:171","msg":"trace[1214304883] linearizableReadLoop","detail":"{readStateIndex:9245; appliedIndex:9244; }","duration":"139.709639ms","start":"2026-04-07T09:58:43.982858Z","end":"2026-04-07T09:58:44.122567Z","steps":["trace[1214304883] 'read index received' (duration: 137.263889ms)","trace[1214304883] 'applied index is now lower than readState.Index' (duration: 2.44443ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:58:44.122749Z","caller":"traceutil/trace.go:171","msg":"trace[882629745] transaction","detail":"{read_only:false; response_revision:8923; number_of_response:1; }","duration":"157.337609ms","start":"2026-04-07T09:58:43.965354Z","end":"2026-04-07T09:58:44.122691Z","steps":["trace[882629745] 'process raft request' (duration: 154.89558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:58:44.122946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.057727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T09:58:44.123024Z","caller":"traceutil/trace.go:171","msg":"trace[2086290572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8923; }","duration":"140.176241ms","start":"2026-04-07T09:58:43.982828Z","end":"2026-04-07T09:58:44.123004Z","steps":["trace[2086290572] 'agreement among raft nodes before linearized reading' (duration: 139.924175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:58:44.123127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.559696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:58:44.123203Z","caller":"traceutil/trace.go:171","msg":"trace[297914740] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:8923; }","duration":"137.663109ms","start":"2026-04-07T09:58:43.985521Z","end":"2026-04-07T09:58:44.123184Z","steps":["trace[297914740] 'agreement among raft nodes before linearized reading' (duration: 137.241679ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:58:47.582936Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000247d.wal"} {"level":"info","ts":"2026-04-07T09:59:47.639105Z","caller":"traceutil/trace.go:171","msg":"trace[1032852969] transaction","detail":"{read_only:false; response_revision:9572; number_of_response:1; }","duration":"131.454898ms","start":"2026-04-07T09:59:47.507624Z","end":"2026-04-07T09:59:47.639079Z","steps":["trace[1032852969] 'process raft request' (duration: 131.250473ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:59:59.238181Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3cec112083e8d3ff","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T09:59:59.244856Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-07T09:59:59.245049Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-07T10:01:52.642316Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8000} {"level":"info","ts":"2026-04-07T10:01:52.710172Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8000,"took":"65.202006ms","hash":1136217700,"current-db-size-bytes":55398400,"current-db-size":"55 MB","current-db-size-in-use-bytes":28651520,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-07T10:01:52.710268Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1136217700,"revision":8000,"compact-revision":5832} {"level":"warn","ts":"2026-04-07T10:02:05.296546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.868941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:40 size:368113"} {"level":"info","ts":"2026-04-07T10:02:05.296699Z","caller":"traceutil/trace.go:171","msg":"trace[857243944] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:40; response_revision:10608; }","duration":"140.077277ms","start":"2026-04-07T10:02:05.156596Z","end":"2026-04-07T10:02:05.296673Z","steps":["trace[857243944] 'range keys from in-memory index tree' (duration: 139.188455ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T10:02:06.378983Z","caller":"traceutil/trace.go:171","msg":"trace[1753168225] linearizableReadLoop","detail":"{readStateIndex:10990; appliedIndex:10989; }","duration":"235.401315ms","start":"2026-04-07T10:02:06.143535Z","end":"2026-04-07T10:02:06.378937Z","steps":["trace[1753168225] 'read index received' (duration: 218.782291ms)","trace[1753168225] 'applied index is now lower than readState.Index' (duration: 16.617364ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T10:02:06.379169Z","caller":"traceutil/trace.go:171","msg":"trace[1612193150] transaction","detail":"{read_only:false; response_revision:10615; number_of_response:1; }","duration":"246.090955ms","start":"2026-04-07T10:02:06.133064Z","end":"2026-04-07T10:02:06.379155Z","steps":["trace[1612193150] 'process raft request' (duration: 229.264436ms)","trace[1612193150] 'compare' (duration: 15.958888ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T10:02:06.379464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.984826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T10:02:06.379558Z","caller":"traceutil/trace.go:171","msg":"trace[307720556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10615; }","duration":"233.135209ms","start":"2026-04-07T10:02:06.146404Z","end":"2026-04-07T10:02:06.379539Z","steps":["trace[307720556] 'agreement among raft nodes before linearized reading' (duration: 232.915244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T10:02:06.379653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.097562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-07T10:02:06.379718Z","caller":"traceutil/trace.go:171","msg":"trace[305862914] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:10615; }","duration":"236.205324ms","start":"2026-04-07T10:02:06.143494Z","end":"2026-04-07T10:02:06.379699Z","steps":["trace[305862914] 'agreement among raft nodes before linearized reading' (duration: 235.957008ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T10:02:11.534363Z","caller":"traceutil/trace.go:171","msg":"trace[340923533] transaction","detail":"{read_only:false; response_revision:10667; number_of_response:1; }","duration":"120.383008ms","start":"2026-04-07T10:02:11.412273Z","end":"2026-04-07T10:02:11.532656Z","steps":["trace[340923533] 'process raft request' (duration: 120.05574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T10:02:11.753232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.403331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T10:02:11.753335Z","caller":"traceutil/trace.go:171","msg":"trace[1949311704] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:10667; }","duration":"107.580536ms","start":"2026-04-07T10:02:11.64573Z","end":"2026-04-07T10:02:11.753311Z","steps":["trace[1949311704] 'count revisions from in-memory index tree' (duration: 107.310729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T10:02:11.753759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.045253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T10:02:11.753801Z","caller":"traceutil/trace.go:171","msg":"trace[1324386631] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10667; }","duration":"137.173846ms","start":"2026-04-07T10:02:11.616617Z","end":"2026-04-07T10:02:11.753791Z","steps":["trace[1324386631] 'range keys from in-memory index tree' (duration: 136.908709ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T10:02:11.854646Z","caller":"traceutil/trace.go:171","msg":"trace[222352738] transaction","detail":"{read_only:false; response_revision:10668; number_of_response:1; }","duration":"143.781897ms","start":"2026-04-07T10:02:11.71082Z","end":"2026-04-07T10:02:11.854602Z","steps":["trace[222352738] 'process raft request' (duration: 143.440928ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T10:02:11.896291Z","caller":"traceutil/trace.go:171","msg":"trace[1565697866] linearizableReadLoop","detail":"{readStateIndex:11046; appliedIndex:11043; }","duration":"141.400418ms","start":"2026-04-07T10:02:11.754855Z","end":"2026-04-07T10:02:11.896255Z","steps":["trace[1565697866] 'read index received' (duration: 99.412327ms)","trace[1565697866] 'applied index is now lower than readState.Index' (duration: 41.986871ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T10:02:11.896339Z","caller":"traceutil/trace.go:171","msg":"trace[506453902] transaction","detail":"{read_only:false; response_revision:10669; number_of_response:1; }","duration":"185.186093ms","start":"2026-04-07T10:02:11.711122Z","end":"2026-04-07T10:02:11.896308Z","steps":["trace[506453902] 'process raft request' (duration: 184.902627ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T10:02:11.896451Z","caller":"traceutil/trace.go:171","msg":"trace[144790397] transaction","detail":"{read_only:false; response_revision:10670; number_of_response:1; }","duration":"176.568914ms","start":"2026-04-07T10:02:11.719865Z","end":"2026-04-07T10:02:11.896434Z","steps":["trace[144790397] 'process raft request' (duration: 176.334808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T10:02:11.897206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.323932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T10:02:11.897282Z","caller":"traceutil/trace.go:171","msg":"trace[589283007] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10670; }","duration":"142.450574ms","start":"2026-04-07T10:02:11.754811Z","end":"2026-04-07T10:02:11.897261Z","steps":["trace[589283007] 'agreement among raft nodes before linearized reading' (duration: 142.239089ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T10:06:52.649444Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10465} {"level":"info","ts":"2026-04-07T10:06:52.736851Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10465,"took":"83.899ms","hash":2478661075,"current-db-size-bytes":55398400,"current-db-size":"55 MB","current-db-size-in-use-bytes":21958656,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-07T10:06:52.736949Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2478661075,"revision":10465,"compact-revision":8000}