{"level":"warn","ts":"2026-05-09T16:51:34.539433Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T16:51:34.539527Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223: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.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223: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-05-09T16:51:34.539621Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T16:51:34.539635Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-05-09T16:51:34.539672Z","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-05-09T16:51:34.54031Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]} {"level":"info","ts":"2026-05-09T16:51:34.540415Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223: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-05-09T16:51:34.544331Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.629996ms"} {"level":"info","ts":"2026-05-09T16:51:34.550311Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"} {"level":"info","ts":"2026-05-09T16:51:34.550387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"} {"level":"info","ts":"2026-05-09T16:51:34.550434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"} {"level":"info","ts":"2026-05-09T16:51:34.550448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-09T16:51:34.550456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"} {"level":"info","ts":"2026-05-09T16:51:34.550513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"warn","ts":"2026-05-09T16:51:34.555155Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-09T16:51:34.556195Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-09T16:51:34.556997Z","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-05-09T16:51:34.558782Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-09T16:51:34.56278Z","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-05-09T16:51:34.562887Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-09T16:51:34.562869Z","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-05-09T16:51:34.563391Z","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-05-09T16:51:34.564419Z","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-05-09T16:51:34.564547Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-05-09T16:51:34.564667Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-05-09T16:51:34.564817Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-09T16:51:34.564828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"info","ts":"2026-05-09T16:51:34.564869Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-09T16:51:34.565167Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-05-09T16:51:35.251017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"} {"level":"info","ts":"2026-05-09T16:51:35.251086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-09T16:51:35.251111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"} {"level":"info","ts":"2026-05-09T16:51:35.251132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"} {"level":"info","ts":"2026-05-09T16:51:35.251143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-05-09T16:51:35.251159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"} {"level":"info","ts":"2026-05-09T16:51:35.251172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-05-09T16:51:35.252469Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T16:51:35.253295Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"} {"level":"info","ts":"2026-05-09T16:51:35.253467Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T16:51:35.253507Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T16:51:35.255517Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-09T16:51:35.255567Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-09T16:51:35.256323Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T16:51:35.256603Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T16:51:35.256645Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T16:51:35.257231Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"} {"level":"info","ts":"2026-05-09T16:51:35.25875Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-09T16:51:38.132296Z","caller":"traceutil/trace.go:171","msg":"trace[401184719] transaction","detail":"{read_only:false; response_revision:87; number_of_response:1; }","duration":"137.984747ms","start":"2026-05-09T16:51:37.994272Z","end":"2026-05-09T16:51:38.132256Z","steps":["trace[401184719] 'process raft request' (duration: 92.723478ms)","trace[401184719] 'compare' (duration: 45.056176ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:38.603196Z","caller":"traceutil/trace.go:171","msg":"trace[2050502298] transaction","detail":"{read_only:false; response_revision:101; number_of_response:1; }","duration":"154.88636ms","start":"2026-05-09T16:51:38.448268Z","end":"2026-05-09T16:51:38.603154Z","steps":["trace[2050502298] 'process raft request' (duration: 89.800569ms)","trace[2050502298] 'compare' (duration: 64.900869ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:38.734274Z","caller":"traceutil/trace.go:171","msg":"trace[1179083274] transaction","detail":"{read_only:false; response_revision:103; number_of_response:1; }","duration":"100.062739ms","start":"2026-05-09T16:51:38.634161Z","end":"2026-05-09T16:51:38.734223Z","steps":["trace[1179083274] 'process raft request' (duration: 82.953702ms)","trace[1179083274] 'compare' (duration: 16.770433ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:46.292485Z","caller":"traceutil/trace.go:171","msg":"trace[1236585408] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"139.184722ms","start":"2026-05-09T16:51:46.153257Z","end":"2026-05-09T16:51:46.292442Z","steps":["trace[1236585408] 'process raft request' (duration: 138.97676ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:51.957552Z","caller":"traceutil/trace.go:171","msg":"trace[1922521905] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"387.257202ms","start":"2026-05-09T16:51:51.570261Z","end":"2026-05-09T16:51:51.957519Z","steps":["trace[1922521905] 'process raft request' (duration: 387.07441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:51.958322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:51.570227Z","time spent":"387.417573ms","remote":"127.0.0.1:57944","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-05-09T16:51:52.68651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.347977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"info","ts":"2026-05-09T16:51:52.686841Z","caller":"traceutil/trace.go:171","msg":"trace[960634001] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:374; }","duration":"576.745981ms","start":"2026-05-09T16:51:52.110071Z","end":"2026-05-09T16:51:52.686817Z","steps":["trace[960634001] 'range keys from in-memory index tree' (duration: 576.172375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:52.687284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:52.110043Z","time spent":"577.220074ms","remote":"127.0.0.1:57838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4397,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-09T16:51:53.266873Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521226053213755748,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-09T16:51:53.269163Z","caller":"traceutil/trace.go:171","msg":"trace[52287444] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:387; }","duration":"503.312862ms","start":"2026-05-09T16:51:52.765825Z","end":"2026-05-09T16:51:53.269138Z","steps":["trace[52287444] 'read index received' (duration: 503.1257ms)","trace[52287444] 'applied index is now lower than readState.Index' (duration: 186.112µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:53.269243Z","caller":"traceutil/trace.go:171","msg":"trace[1877525974] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"570.734205ms","start":"2026-05-09T16:51:52.698462Z","end":"2026-05-09T16:51:53.269196Z","steps":["trace[1877525974] 'process raft request' (duration: 570.512833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:53.269317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.468153ms","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-05-09T16:51:53.269391Z","caller":"traceutil/trace.go:171","msg":"trace[1979066465] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:375; }","duration":"503.627774ms","start":"2026-05-09T16:51:52.765751Z","end":"2026-05-09T16:51:53.269379Z","steps":["trace[1979066465] 'agreement among raft nodes before linearized reading' (duration: 503.483902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:53.269408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.097982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-09T16:51:53.269431Z","caller":"traceutil/trace.go:171","msg":"trace[2104761755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:375; }","duration":"113.168262ms","start":"2026-05-09T16:51:53.156254Z","end":"2026-05-09T16:51:53.269423Z","steps":["trace[2104761755] 'agreement among raft nodes before linearized reading' (duration: 113.119051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:53.269426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:52.765683Z","time spent":"503.733536ms","remote":"127.0.0.1:57944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-09T16:51:53.269518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:52.698431Z","time spent":"570.963648ms","remote":"127.0.0.1:57840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7288,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:51:53.269317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.735211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-09T16:51:53.269663Z","caller":"traceutil/trace.go:171","msg":"trace[443739643] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:375; }","duration":"143.124183ms","start":"2026-05-09T16:51:53.126515Z","end":"2026-05-09T16:51:53.269639Z","steps":["trace[443739643] 'agreement among raft nodes before linearized reading' (duration: 142.74771ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:07.064862Z","caller":"traceutil/trace.go:171","msg":"trace[104593906] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"232.474545ms","start":"2026-05-09T16:52:06.832354Z","end":"2026-05-09T16:52:07.064829Z","steps":["trace[104593906] 'process raft request' (duration: 232.271904ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:07.077075Z","caller":"traceutil/trace.go:171","msg":"trace[464177239] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"242.472198ms","start":"2026-05-09T16:52:06.834568Z","end":"2026-05-09T16:52:07.07704Z","steps":["trace[464177239] 'process raft request' (duration: 242.261766ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:07.07723Z","caller":"traceutil/trace.go:171","msg":"trace[1235178257] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"239.663696ms","start":"2026-05-09T16:52:06.83746Z","end":"2026-05-09T16:52:07.077123Z","steps":["trace[1235178257] 'process raft request' (duration: 239.501484ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:07.077262Z","caller":"traceutil/trace.go:171","msg":"trace[165556169] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"226.711898ms","start":"2026-05-09T16:52:06.850467Z","end":"2026-05-09T16:52:07.077179Z","steps":["trace[165556169] 'process raft request' (duration: 226.551047ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:07.077365Z","caller":"traceutil/trace.go:171","msg":"trace[1472463162] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"222.062939ms","start":"2026-05-09T16:52:06.855288Z","end":"2026-05-09T16:52:07.077351Z","steps":["trace[1472463162] 'process raft request' (duration: 221.851178ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:07.077369Z","caller":"traceutil/trace.go:171","msg":"trace[1731897939] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"222.06929ms","start":"2026-05-09T16:52:06.855211Z","end":"2026-05-09T16:52:07.07728Z","steps":["trace[1731897939] 'process raft request' (duration: 221.895608ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:07.077515Z","caller":"traceutil/trace.go:171","msg":"trace[1792184786] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"222.033399ms","start":"2026-05-09T16:52:06.855473Z","end":"2026-05-09T16:52:07.077506Z","steps":["trace[1792184786] 'process raft request' (duration: 221.694897ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:07.07756Z","caller":"traceutil/trace.go:171","msg":"trace[1168680206] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"220.767868ms","start":"2026-05-09T16:52:06.85678Z","end":"2026-05-09T16:52:07.077548Z","steps":["trace[1168680206] 'process raft request' (duration: 220.606377ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:07.077777Z","caller":"traceutil/trace.go:171","msg":"trace[1590417311] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"210.580665ms","start":"2026-05-09T16:52:06.867186Z","end":"2026-05-09T16:52:07.077766Z","steps":["trace[1590417311] 'process raft request' (duration: 210.317742ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:07.077861Z","caller":"traceutil/trace.go:171","msg":"trace[795020029] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"183.940336ms","start":"2026-05-09T16:52:06.893907Z","end":"2026-05-09T16:52:07.077848Z","steps":["trace[795020029] 'process raft request' (duration: 183.792684ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:07.077858Z","caller":"traceutil/trace.go:171","msg":"trace[1456701237] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"209.558417ms","start":"2026-05-09T16:52:06.868232Z","end":"2026-05-09T16:52:07.07779Z","steps":["trace[1456701237] 'process raft request' (duration: 209.313614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:08.800705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.031005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:570105"} {"level":"info","ts":"2026-05-09T16:52:08.800815Z","caller":"traceutil/trace.go:171","msg":"trace[204161806] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:551; }","duration":"196.224978ms","start":"2026-05-09T16:52:08.604569Z","end":"2026-05-09T16:52:08.800794Z","steps":["trace[204161806] 'range keys from in-memory index tree' (duration: 195.44226ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:13.535829Z","caller":"traceutil/trace.go:171","msg":"trace[1332669431] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"164.594822ms","start":"2026-05-09T16:52:13.371201Z","end":"2026-05-09T16:52:13.535796Z","steps":["trace[1332669431] 'read index received' (duration: 164.226055ms)","trace[1332669431] 'applied index is now lower than readState.Index' (duration: 367.107µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:52:13.536311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.072126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:52:13.536383Z","caller":"traceutil/trace.go:171","msg":"trace[1776782076] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"165.208493ms","start":"2026-05-09T16:52:13.371157Z","end":"2026-05-09T16:52:13.536366Z","steps":["trace[1776782076] 'agreement among raft nodes before linearized reading' (duration: 165.049135ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:13.53641Z","caller":"traceutil/trace.go:171","msg":"trace[1715228426] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"201.98187ms","start":"2026-05-09T16:52:13.334374Z","end":"2026-05-09T16:52:13.536356Z","steps":["trace[1715228426] 'process raft request' (duration: 201.188021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:13.536464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.464155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-09T16:52:13.536543Z","caller":"traceutil/trace.go:171","msg":"trace[1231511420] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:579; }","duration":"106.58557ms","start":"2026-05-09T16:52:13.42994Z","end":"2026-05-09T16:52:13.536525Z","steps":["trace[1231511420] 'agreement among raft nodes before linearized reading' (duration: 106.330438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:14.039417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.22804ms","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-05-09T16:52:14.039543Z","caller":"traceutil/trace.go:171","msg":"trace[590659883] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:579; }","duration":"397.371966ms","start":"2026-05-09T16:52:13.642152Z","end":"2026-05-09T16:52:14.039524Z","steps":["trace[590659883] 'range keys from in-memory index tree' (duration: 397.214759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:14.040382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.978048ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:52:14.040634Z","caller":"traceutil/trace.go:171","msg":"trace[188864713] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"499.96327ms","start":"2026-05-09T16:52:13.540626Z","end":"2026-05-09T16:52:14.040589Z","steps":["trace[188864713] 'process raft request' (duration: 55.545801ms)","trace[188864713] 'compare' (duration: 443.231842ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:52:14.040905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:13.540603Z","time spent":"500.15706ms","remote":"127.0.0.1:57944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:52:14.040964Z","caller":"traceutil/trace.go:171","msg":"trace[924164854] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"500.131258ms","start":"2026-05-09T16:52:13.540816Z","end":"2026-05-09T16:52:14.040947Z","steps":["trace[924164854] 'process raft request' (duration: 499.632564ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:14.041566Z","caller":"traceutil/trace.go:171","msg":"trace[1534256891] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"466.855031ms","start":"2026-05-09T16:52:13.574699Z","end":"2026-05-09T16:52:14.041554Z","steps":["trace[1534256891] 'process raft request' (duration: 466.292804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:14.041601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:13.540771Z","time spent":"500.665714ms","remote":"127.0.0.1:57944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:52:14.041689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:13.574652Z","time spent":"466.954277ms","remote":"127.0.0.1:57752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:52:14.041013Z","caller":"traceutil/trace.go:171","msg":"trace[1473747038] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"499.99017ms","start":"2026-05-09T16:52:13.540991Z","end":"2026-05-09T16:52:14.040981Z","steps":["trace[1473747038] 'process raft request' (duration: 499.684945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:14.042055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:13.54098Z","time spent":"501.039881ms","remote":"127.0.0.1:57944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:52:14.041085Z","caller":"traceutil/trace.go:171","msg":"trace[720659722] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"499.56956ms","start":"2026-05-09T16:52:13.541504Z","end":"2026-05-09T16:52:14.041074Z","steps":["trace[720659722] 'process raft request' (duration: 499.198812ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:14.042212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:13.541481Z","time spent":"500.698286ms","remote":"127.0.0.1:57944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:52:15.456854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.171868ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:52:15.457157Z","caller":"traceutil/trace.go:171","msg":"trace[134104054] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"1.349567078s","start":"2026-05-09T16:52:14.107556Z","end":"2026-05-09T16:52:15.457123Z","steps":["trace[134104054] 'process raft request' (duration: 628.038703ms)","trace[134104054] 'compare' (duration: 721.038881ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:52:15.457245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:14.107524Z","time spent":"1.349685113s","remote":"127.0.0.1:58176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T16:52:15.468224Z","caller":"traceutil/trace.go:171","msg":"trace[1974795246] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:604; }","duration":"268.69466ms","start":"2026-05-09T16:52:15.1995Z","end":"2026-05-09T16:52:15.468195Z","steps":["trace[1974795246] 'read index received' (duration: 60.093µs)","trace[1974795246] 'applied index is now lower than readState.Index' (duration: 268.632897ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:52:15.468503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.897151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T16:52:15.468528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.88644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:52:15.468551Z","caller":"traceutil/trace.go:171","msg":"trace[4692933] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:585; }","duration":"269.072899ms","start":"2026-05-09T16:52:15.199465Z","end":"2026-05-09T16:52:15.468538Z","steps":["trace[4692933] 'agreement among raft nodes before linearized reading' (duration: 268.89131ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:15.46857Z","caller":"traceutil/trace.go:171","msg":"trace[2061118079] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:585; }","duration":"268.968073ms","start":"2026-05-09T16:52:15.199589Z","end":"2026-05-09T16:52:15.468557Z","steps":["trace[2061118079] 'agreement among raft nodes before linearized reading' (duration: 268.872669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:15.468673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.857852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:52:15.468781Z","caller":"traceutil/trace.go:171","msg":"trace[1335984205] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:585; }","duration":"222.049001ms","start":"2026-05-09T16:52:15.246706Z","end":"2026-05-09T16:52:15.468755Z","steps":["trace[1335984205] 'agreement among raft nodes before linearized reading' (duration: 221.832161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:15.468779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.204487ms","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-05-09T16:52:15.468913Z","caller":"traceutil/trace.go:171","msg":"trace[356345421] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:585; }","duration":"127.373575ms","start":"2026-05-09T16:52:15.341484Z","end":"2026-05-09T16:52:15.468857Z","steps":["trace[356345421] 'agreement among raft nodes before linearized reading' (duration: 127.26378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:15.46899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.07981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:52:15.469066Z","caller":"traceutil/trace.go:171","msg":"trace[1606870749] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:585; }","duration":"223.166945ms","start":"2026-05-09T16:52:15.245884Z","end":"2026-05-09T16:52:15.469051Z","steps":["trace[1606870749] 'agreement among raft nodes before linearized reading' (duration: 223.068399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:15.469093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.735883ms","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-05-09T16:52:15.469134Z","caller":"traceutil/trace.go:171","msg":"trace[770926458] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:585; }","duration":"127.792856ms","start":"2026-05-09T16:52:15.341331Z","end":"2026-05-09T16:52:15.469124Z","steps":["trace[770926458] 'agreement among raft nodes before linearized reading' (duration: 127.730103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:15.469218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.337216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T16:52:15.469282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.459955ms","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-05-09T16:52:15.469316Z","caller":"traceutil/trace.go:171","msg":"trace[1668216512] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:585; }","duration":"170.542798ms","start":"2026-05-09T16:52:15.298764Z","end":"2026-05-09T16:52:15.469307Z","steps":["trace[1668216512] 'agreement among raft nodes before linearized reading' (duration: 170.488456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:15.469325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.452928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T16:52:15.469376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.234319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:52:15.469436Z","caller":"traceutil/trace.go:171","msg":"trace[1149653950] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:585; }","duration":"127.331583ms","start":"2026-05-09T16:52:15.342094Z","end":"2026-05-09T16:52:15.469426Z","steps":["trace[1149653950] 'agreement among raft nodes before linearized reading' (duration: 127.209157ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:15.469479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.322454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:52:15.469284Z","caller":"traceutil/trace.go:171","msg":"trace[487872943] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"101.395879ms","start":"2026-05-09T16:52:15.367853Z","end":"2026-05-09T16:52:15.469249Z","steps":["trace[487872943] 'agreement among raft nodes before linearized reading' (duration: 101.332596ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:15.469547Z","caller":"traceutil/trace.go:171","msg":"trace[1632359956] 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:585; }","duration":"242.371017ms","start":"2026-05-09T16:52:15.227136Z","end":"2026-05-09T16:52:15.469507Z","steps":["trace[1632359956] 'agreement among raft nodes before linearized reading' (duration: 242.284263ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:15.469398Z","caller":"traceutil/trace.go:171","msg":"trace[433690518] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:585; }","duration":"223.556243ms","start":"2026-05-09T16:52:15.245831Z","end":"2026-05-09T16:52:15.469387Z","steps":["trace[433690518] 'agreement among raft nodes before linearized reading' (duration: 223.424127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:15.469796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.289592ms","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":"info","ts":"2026-05-09T16:52:15.469839Z","caller":"traceutil/trace.go:171","msg":"trace[1258538423] 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:585; }","duration":"243.345404ms","start":"2026-05-09T16:52:15.226485Z","end":"2026-05-09T16:52:15.46983Z","steps":["trace[1258538423] 'agreement among raft nodes before linearized reading' (duration: 243.252389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:15.469458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.719007ms","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-05-09T16:52:15.469945Z","caller":"traceutil/trace.go:171","msg":"trace[1825656049] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:585; }","duration":"171.245343ms","start":"2026-05-09T16:52:15.298689Z","end":"2026-05-09T16:52:15.469935Z","steps":["trace[1825656049] 'agreement among raft nodes before linearized reading' (duration: 170.740618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:15.470066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.878178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:52:15.470129Z","caller":"traceutil/trace.go:171","msg":"trace[715476925] 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:585; }","duration":"243.958532ms","start":"2026-05-09T16:52:15.22616Z","end":"2026-05-09T16:52:15.470119Z","steps":["trace[715476925] 'agreement among raft nodes before linearized reading' (duration: 243.875058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:15.470268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.413083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:52:15.470309Z","caller":"traceutil/trace.go:171","msg":"trace[1532991229] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:585; }","duration":"270.509618ms","start":"2026-05-09T16:52:15.19979Z","end":"2026-05-09T16:52:15.4703Z","steps":["trace[1532991229] 'agreement among raft nodes before linearized reading' (duration: 270.447015ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:16.447055Z","caller":"traceutil/trace.go:171","msg":"trace[628635589] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"113.943066ms","start":"2026-05-09T16:52:16.333088Z","end":"2026-05-09T16:52:16.447031Z","steps":["trace[628635589] 'process raft request' (duration: 113.783038ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:16.451848Z","caller":"traceutil/trace.go:171","msg":"trace[1804029750] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:617; }","duration":"102.65588ms","start":"2026-05-09T16:52:16.349169Z","end":"2026-05-09T16:52:16.451825Z","steps":["trace[1804029750] 'read index received' (duration: 97.750257ms)","trace[1804029750] 'applied index is now lower than readState.Index' (duration: 4.904493ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:52:16.451957Z","caller":"traceutil/trace.go:171","msg":"trace[1101014075] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"105.751969ms","start":"2026-05-09T16:52:16.346193Z","end":"2026-05-09T16:52:16.451945Z","steps":["trace[1101014075] 'process raft request' (duration: 105.508186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:16.452132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.883701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T16:52:16.452181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.974756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:52:16.452248Z","caller":"traceutil/trace.go:171","msg":"trace[2008209371] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:598; }","duration":"103.091081ms","start":"2026-05-09T16:52:16.349142Z","end":"2026-05-09T16:52:16.452234Z","steps":["trace[2008209371] 'agreement among raft nodes before linearized reading' (duration: 102.956305ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:16.452225Z","caller":"traceutil/trace.go:171","msg":"trace[1546301977] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:598; }","duration":"103.008688ms","start":"2026-05-09T16:52:16.349201Z","end":"2026-05-09T16:52:16.45221Z","steps":["trace[1546301977] 'agreement among raft nodes before linearized reading' (duration: 102.861751ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:17.754765Z","caller":"traceutil/trace.go:171","msg":"trace[818413489] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"115.653773ms","start":"2026-05-09T16:52:17.639085Z","end":"2026-05-09T16:52:17.754739Z","steps":["trace[818413489] 'process raft request' (duration: 104.384185ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:18.605556Z","caller":"traceutil/trace.go:171","msg":"trace[1062354006] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"212.29873ms","start":"2026-05-09T16:52:18.393221Z","end":"2026-05-09T16:52:18.60552Z","steps":["trace[1062354006] 'process raft request' (duration: 160.842915ms)","trace[1062354006] 'compare' (duration: 51.302797ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:52:18.642591Z","caller":"traceutil/trace.go:171","msg":"trace[211171871] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"246.260392ms","start":"2026-05-09T16:52:18.39628Z","end":"2026-05-09T16:52:18.64254Z","steps":["trace[211171871] 'process raft request' (duration: 245.931648ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:18.642807Z","caller":"traceutil/trace.go:171","msg":"trace[1086306862] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"212.940508ms","start":"2026-05-09T16:52:18.429832Z","end":"2026-05-09T16:52:18.642772Z","steps":["trace[1086306862] 'process raft request' (duration: 212.620612ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:18.769625Z","caller":"traceutil/trace.go:171","msg":"trace[1377883193] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"122.674086ms","start":"2026-05-09T16:52:18.646926Z","end":"2026-05-09T16:52:18.7696Z","steps":["trace[1377883193] 'read index received' (duration: 110.583496ms)","trace[1377883193] 'applied index is now lower than readState.Index' (duration: 12.08985ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:52:18.769823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.934737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-484kr\" ","response":"range_response_count:1 size:4683"} {"level":"info","ts":"2026-05-09T16:52:18.769853Z","caller":"traceutil/trace.go:171","msg":"trace[362343416] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-484kr; range_end:; response_count:1; response_revision:632; }","duration":"123.038492ms","start":"2026-05-09T16:52:18.646807Z","end":"2026-05-09T16:52:18.769845Z","steps":["trace[362343416] 'agreement among raft nodes before linearized reading' (duration: 122.883825ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:18.769883Z","caller":"traceutil/trace.go:171","msg":"trace[1134685078] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"139.302106ms","start":"2026-05-09T16:52:18.630515Z","end":"2026-05-09T16:52:18.769817Z","steps":["trace[1134685078] 'process raft request' (duration: 127.021898ms)","trace[1134685078] 'compare' (duration: 11.948043ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:52:18.848785Z","caller":"traceutil/trace.go:171","msg":"trace[82023986] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"199.328978ms","start":"2026-05-09T16:52:18.649429Z","end":"2026-05-09T16:52:18.848758Z","steps":["trace[82023986] 'process raft request' (duration: 199.193352ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:32.358893Z","caller":"traceutil/trace.go:171","msg":"trace[718966542] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"182.952261ms","start":"2026-05-09T16:52:32.175913Z","end":"2026-05-09T16:52:32.358865Z","steps":["trace[718966542] 'process raft request' (duration: 182.778154ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:32.368997Z","caller":"traceutil/trace.go:171","msg":"trace[187010324] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"192.468147ms","start":"2026-05-09T16:52:32.176511Z","end":"2026-05-09T16:52:32.368979Z","steps":["trace[187010324] 'process raft request' (duration: 192.205736ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:32.368995Z","caller":"traceutil/trace.go:171","msg":"trace[559722554] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"192.452837ms","start":"2026-05-09T16:52:32.176515Z","end":"2026-05-09T16:52:32.368968Z","steps":["trace[559722554] 'process raft request' (duration: 192.399035ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:32.369123Z","caller":"traceutil/trace.go:171","msg":"trace[573773484] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"192.53004ms","start":"2026-05-09T16:52:32.176521Z","end":"2026-05-09T16:52:32.369051Z","steps":["trace[573773484] 'process raft request' (duration: 192.359063ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:33.064271Z","caller":"traceutil/trace.go:171","msg":"trace[1720738274] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"625.169737ms","start":"2026-05-09T16:52:32.43908Z","end":"2026-05-09T16:52:33.06425Z","steps":["trace[1720738274] 'process raft request' (duration: 624.739769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:33.064411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:32.439059Z","time spent":"625.269291ms","remote":"127.0.0.1:57840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:52:33.085981Z","caller":"traceutil/trace.go:171","msg":"trace[1605170512] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"149.160324ms","start":"2026-05-09T16:52:32.936785Z","end":"2026-05-09T16:52:33.085945Z","steps":["trace[1605170512] 'process raft request' (duration: 148.944605ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:53:08.046877Z","caller":"traceutil/trace.go:171","msg":"trace[1126742592] linearizableReadLoop","detail":"{readStateIndex:1210; appliedIndex:1209; }","duration":"188.288639ms","start":"2026-05-09T16:53:07.858563Z","end":"2026-05-09T16:53:08.046852Z","steps":["trace[1126742592] 'read index received' (duration: 188.059193ms)","trace[1126742592] 'applied index is now lower than readState.Index' (duration: 228.336µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:53:08.046948Z","caller":"traceutil/trace.go:171","msg":"trace[377785837] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"403.155398ms","start":"2026-05-09T16:53:07.643774Z","end":"2026-05-09T16:53:08.046929Z","steps":["trace[377785837] 'process raft request' (duration: 402.933812ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:53:08.047069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:53:07.643747Z","time spent":"403.25134ms","remote":"127.0.0.1:57944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:53:08.047085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.511765ms","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-05-09T16:53:08.047119Z","caller":"traceutil/trace.go:171","msg":"trace[1842155295] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1175; }","duration":"188.586267ms","start":"2026-05-09T16:53:07.858522Z","end":"2026-05-09T16:53:08.047109Z","steps":["trace[1842155295] 'agreement among raft nodes before linearized reading' (duration: 188.460164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:53:08.047256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.108444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:53:08.047291Z","caller":"traceutil/trace.go:171","msg":"trace[1478041684] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1175; }","duration":"128.178756ms","start":"2026-05-09T16:53:07.919102Z","end":"2026-05-09T16:53:08.047281Z","steps":["trace[1478041684] 'agreement among raft nodes before linearized reading' (duration: 128.131085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:53:52.223306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.862554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:53:52.2234Z","caller":"traceutil/trace.go:171","msg":"trace[714667643] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1820; }","duration":"276.021177ms","start":"2026-05-09T16:53:51.947359Z","end":"2026-05-09T16:53:52.22338Z","steps":["trace[714667643] 'count revisions from in-memory index tree' (duration: 275.717882ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:53:52.223925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.536613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-db904a21-4e4b-4f94-a2da-7795a57ebbe3\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-09T16:53:52.224008Z","caller":"traceutil/trace.go:171","msg":"trace[75247081] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-db904a21-4e4b-4f94-a2da-7795a57ebbe3; range_end:; response_count:1; response_revision:1820; }","duration":"321.662504ms","start":"2026-05-09T16:53:51.902326Z","end":"2026-05-09T16:53:52.223989Z","steps":["trace[75247081] 'range keys from in-memory index tree' (duration: 321.266797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:53:52.223961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.898235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-db904a21-4e4b-4f94-a2da-7795a57ebbe3\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-05-09T16:53:52.22405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:53:51.902308Z","time spent":"321.732926ms","remote":"127.0.0.1:57840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-db904a21-4e4b-4f94-a2da-7795a57ebbe3\" "} {"level":"info","ts":"2026-05-09T16:53:52.224153Z","caller":"traceutil/trace.go:171","msg":"trace[2105886871] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-db904a21-4e4b-4f94-a2da-7795a57ebbe3; range_end:; response_count:1; response_revision:1820; }","duration":"424.315383ms","start":"2026-05-09T16:53:51.799774Z","end":"2026-05-09T16:53:52.224089Z","steps":["trace[2105886871] 'range keys from in-memory index tree' (duration: 423.696501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:53:52.224381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:53:51.799754Z","time spent":"424.603769ms","remote":"127.0.0.1:57840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-db904a21-4e4b-4f94-a2da-7795a57ebbe3\" "} {"level":"info","ts":"2026-05-09T16:53:52.566824Z","caller":"traceutil/trace.go:171","msg":"trace[1882260470] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"333.81794ms","start":"2026-05-09T16:53:52.232975Z","end":"2026-05-09T16:53:52.566793Z","steps":["trace[1882260470] 'process raft request' (duration: 333.569646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:53:52.567154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:53:52.232958Z","time spent":"333.957144ms","remote":"127.0.0.1:57840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:53:52.801494Z","caller":"traceutil/trace.go:171","msg":"trace[664049155] linearizableReadLoop","detail":"{readStateIndex:1878; appliedIndex:1876; }","duration":"503.488453ms","start":"2026-05-09T16:53:52.297983Z","end":"2026-05-09T16:53:52.801471Z","steps":["trace[664049155] 'read index received' (duration: 268.699246ms)","trace[664049155] 'applied index is now lower than readState.Index' (duration: 234.788217ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:53:52.801665Z","caller":"traceutil/trace.go:171","msg":"trace[1166169487] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"517.508299ms","start":"2026-05-09T16:53:52.284059Z","end":"2026-05-09T16:53:52.801567Z","steps":["trace[1166169487] 'process raft request' (duration: 425.676926ms)","trace[1166169487] 'compare' (duration: 91.419354ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:53:52.801718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.726287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-05-09T16:53:52.801764Z","caller":"traceutil/trace.go:171","msg":"trace[1928895607] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1822; }","duration":"503.804088ms","start":"2026-05-09T16:53:52.29794Z","end":"2026-05-09T16:53:52.801744Z","steps":["trace[1928895607] 'agreement among raft nodes before linearized reading' (duration: 503.662915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:53:52.801804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:53:52.297924Z","time spent":"503.87119ms","remote":"127.0.0.1:49972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4613,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-09T16:53:52.801817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:53:52.28404Z","time spent":"517.700593ms","remote":"127.0.0.1:57944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:53:52.802085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.868935ms","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-05-09T16:53:52.802126Z","caller":"traceutil/trace.go:171","msg":"trace[1187723099] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1822; }","duration":"475.948017ms","start":"2026-05-09T16:53:52.326167Z","end":"2026-05-09T16:53:52.802115Z","steps":["trace[1187723099] 'agreement among raft nodes before linearized reading' (duration: 475.821714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:53:52.802158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:53:52.326147Z","time spent":"476.004519ms","remote":"127.0.0.1:57944","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-05-09T16:53:52.802296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.538557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-05-09T16:53:52.80236Z","caller":"traceutil/trace.go:171","msg":"trace[1224575301] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1822; }","duration":"211.636539ms","start":"2026-05-09T16:53:52.590709Z","end":"2026-05-09T16:53:52.802346Z","steps":["trace[1224575301] 'agreement among raft nodes before linearized reading' (duration: 211.441395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:53:52.802432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.57341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:53:52.802473Z","caller":"traceutil/trace.go:171","msg":"trace[421120674] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1822; }","duration":"435.659022ms","start":"2026-05-09T16:53:52.366805Z","end":"2026-05-09T16:53:52.802464Z","steps":["trace[421120674] 'agreement among raft nodes before linearized reading' (duration: 435.592051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:53:52.802501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:53:52.366779Z","time spent":"435.715464ms","remote":"127.0.0.1:57630","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-05-09T16:53:52.90998Z","caller":"traceutil/trace.go:171","msg":"trace[1795327456] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"103.25035ms","start":"2026-05-09T16:53:52.806702Z","end":"2026-05-09T16:53:52.909952Z","steps":["trace[1795327456] 'process raft request' (duration: 101.916583ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:53:52.910123Z","caller":"traceutil/trace.go:171","msg":"trace[105155766] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"102.590546ms","start":"2026-05-09T16:53:52.807525Z","end":"2026-05-09T16:53:52.910115Z","steps":["trace[105155766] 'process raft request' (duration: 102.382181ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:53:52.910301Z","caller":"traceutil/trace.go:171","msg":"trace[2116652040] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"102.696008ms","start":"2026-05-09T16:53:52.807595Z","end":"2026-05-09T16:53:52.910291Z","steps":["trace[2116652040] 'process raft request' (duration: 102.477604ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:53:52.911261Z","caller":"traceutil/trace.go:171","msg":"trace[556778966] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"101.488273ms","start":"2026-05-09T16:53:52.809336Z","end":"2026-05-09T16:53:52.910824Z","steps":["trace[556778966] 'process raft request' (duration: 100.904532ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:53:59.507345Z","caller":"traceutil/trace.go:171","msg":"trace[1967855651] linearizableReadLoop","detail":"{readStateIndex:1963; appliedIndex:1962; }","duration":"149.990579ms","start":"2026-05-09T16:53:59.35733Z","end":"2026-05-09T16:53:59.507321Z","steps":["trace[1967855651] 'read index received' (duration: 107.969914ms)","trace[1967855651] 'applied index is now lower than readState.Index' (duration: 42.019595ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:53:59.507512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.160962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-05-09T16:53:59.507513Z","caller":"traceutil/trace.go:171","msg":"trace[371708173] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"157.112523ms","start":"2026-05-09T16:53:59.350358Z","end":"2026-05-09T16:53:59.50747Z","steps":["trace[371708173] 'process raft request' (duration: 114.938165ms)","trace[371708173] 'compare' (duration: 41.907303ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:53:59.507551Z","caller":"traceutil/trace.go:171","msg":"trace[2139127016] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1904; }","duration":"150.251333ms","start":"2026-05-09T16:53:59.35729Z","end":"2026-05-09T16:53:59.507541Z","steps":["trace[2139127016] 'agreement among raft nodes before linearized reading' (duration: 150.123711ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:53:59.709936Z","caller":"traceutil/trace.go:171","msg":"trace[586235987] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"105.647698ms","start":"2026-05-09T16:53:59.604264Z","end":"2026-05-09T16:53:59.709912Z","steps":["trace[586235987] 'process raft request' (duration: 55.904757ms)","trace[586235987] 'compare' (duration: 49.638789ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:54:01.558691Z","caller":"traceutil/trace.go:171","msg":"trace[1966065280] linearizableReadLoop","detail":"{readStateIndex:1986; appliedIndex:1985; }","duration":"190.773529ms","start":"2026-05-09T16:54:01.36788Z","end":"2026-05-09T16:54:01.558654Z","steps":["trace[1966065280] 'read index received' (duration: 190.604696ms)","trace[1966065280] 'applied index is now lower than readState.Index' (duration: 168.163µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:54:01.55887Z","caller":"traceutil/trace.go:171","msg":"trace[1040236540] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"224.913909ms","start":"2026-05-09T16:54:01.333946Z","end":"2026-05-09T16:54:01.55886Z","steps":["trace[1040236540] 'process raft request' (duration: 224.596912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:54:01.559039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.151546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:54:01.559067Z","caller":"traceutil/trace.go:171","msg":"trace[182500273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1926; }","duration":"191.214317ms","start":"2026-05-09T16:54:01.367846Z","end":"2026-05-09T16:54:01.55906Z","steps":["trace[182500273] 'agreement among raft nodes before linearized reading' (duration: 191.139096ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:54:01.667241Z","caller":"traceutil/trace.go:171","msg":"trace[2105614236] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"101.426088ms","start":"2026-05-09T16:54:01.565798Z","end":"2026-05-09T16:54:01.667224Z","steps":["trace[2105614236] 'process raft request' (duration: 101.301066ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:54:25.265126Z","caller":"traceutil/trace.go:171","msg":"trace[1596311061] transaction","detail":"{read_only:false; response_revision:2093; number_of_response:1; }","duration":"156.513323ms","start":"2026-05-09T16:54:25.108588Z","end":"2026-05-09T16:54:25.265101Z","steps":["trace[1596311061] 'process raft request' (duration: 156.379911ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:54:25.265847Z","caller":"traceutil/trace.go:171","msg":"trace[81386030] transaction","detail":"{read_only:false; response_revision:2094; number_of_response:1; }","duration":"155.405775ms","start":"2026-05-09T16:54:25.110415Z","end":"2026-05-09T16:54:25.265821Z","steps":["trace[81386030] 'process raft request' (duration: 155.0774ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:54:25.265858Z","caller":"traceutil/trace.go:171","msg":"trace[1330382232] transaction","detail":"{read_only:false; response_revision:2095; number_of_response:1; }","duration":"154.242505ms","start":"2026-05-09T16:54:25.111603Z","end":"2026-05-09T16:54:25.265845Z","steps":["trace[1330382232] 'process raft request' (duration: 154.009602ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:54:31.131072Z","caller":"traceutil/trace.go:171","msg":"trace[1218345938] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"301.48996ms","start":"2026-05-09T16:54:30.829563Z","end":"2026-05-09T16:54:31.131053Z","steps":["trace[1218345938] 'process raft request' (duration: 301.330677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:54:31.131615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:54:30.829546Z","time spent":"301.653992ms","remote":"127.0.0.1:58144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16798,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:54:31.219728Z","caller":"traceutil/trace.go:171","msg":"trace[1859883877] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"287.631889ms","start":"2026-05-09T16:54:30.932069Z","end":"2026-05-09T16:54:31.219701Z","steps":["trace[1859883877] 'process raft request' (duration: 287.448957ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:54:31.219923Z","caller":"traceutil/trace.go:171","msg":"trace[1936853965] linearizableReadLoop","detail":"{readStateIndex:2206; appliedIndex:2204; }","duration":"219.457273ms","start":"2026-05-09T16:54:31.000433Z","end":"2026-05-09T16:54:31.21989Z","steps":["trace[1936853965] 'read index received' (duration: 130.422226ms)","trace[1936853965] 'applied index is now lower than readState.Index' (duration: 89.033207ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:54:31.220059Z","caller":"traceutil/trace.go:171","msg":"trace[730580180] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"153.62697ms","start":"2026-05-09T16:54:31.066335Z","end":"2026-05-09T16:54:31.219962Z","steps":["trace[730580180] 'process raft request' (duration: 153.314495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:54:31.220177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.601679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-09T16:54:31.220221Z","caller":"traceutil/trace.go:171","msg":"trace[1824242025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2135; }","duration":"205.680442ms","start":"2026-05-09T16:54:31.014529Z","end":"2026-05-09T16:54:31.22021Z","steps":["trace[1824242025] 'agreement among raft nodes before linearized reading' (duration: 205.511128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:54:31.220265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.811609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-09T16:54:31.220358Z","caller":"traceutil/trace.go:171","msg":"trace[1393132613] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2135; }","duration":"219.950972ms","start":"2026-05-09T16:54:31.000383Z","end":"2026-05-09T16:54:31.220333Z","steps":["trace[1393132613] 'agreement among raft nodes before linearized reading' (duration: 219.644626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:54:31.220634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.489609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-05-09T16:54:31.220746Z","caller":"traceutil/trace.go:171","msg":"trace[1031725918] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2135; }","duration":"158.645891ms","start":"2026-05-09T16:54:31.06208Z","end":"2026-05-09T16:54:31.220726Z","steps":["trace[1031725918] 'agreement among raft nodes before linearized reading' (duration: 158.220245ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:54:31.427912Z","caller":"traceutil/trace.go:171","msg":"trace[405829146] linearizableReadLoop","detail":"{readStateIndex:2209; appliedIndex:2207; }","duration":"140.204354ms","start":"2026-05-09T16:54:31.28769Z","end":"2026-05-09T16:54:31.427894Z","steps":["trace[405829146] 'read index received' (duration: 80.537371ms)","trace[405829146] 'applied index is now lower than readState.Index' (duration: 59.665973ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:54:31.428026Z","caller":"traceutil/trace.go:171","msg":"trace[528233536] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"203.494516ms","start":"2026-05-09T16:54:31.22452Z","end":"2026-05-09T16:54:31.428014Z","steps":["trace[528233536] 'process raft request' (duration: 143.6515ms)","trace[528233536] 'compare' (duration: 59.559132ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:54:31.428224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.51332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-09T16:54:31.42826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.499009ms","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-05-09T16:54:31.428303Z","caller":"traceutil/trace.go:171","msg":"trace[1624435076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2137; }","duration":"140.633901ms","start":"2026-05-09T16:54:31.287649Z","end":"2026-05-09T16:54:31.428283Z","steps":["trace[1624435076] 'agreement among raft nodes before linearized reading' (duration: 140.399127ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:54:31.428436Z","caller":"traceutil/trace.go:171","msg":"trace[307636731] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"202.899287ms","start":"2026-05-09T16:54:31.225524Z","end":"2026-05-09T16:54:31.428424Z","steps":["trace[307636731] 'process raft request' (duration: 202.327718ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:54:31.428308Z","caller":"traceutil/trace.go:171","msg":"trace[1627037614] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2137; }","duration":"140.59689ms","start":"2026-05-09T16:54:31.287698Z","end":"2026-05-09T16:54:31.428295Z","steps":["trace[1627037614] 'agreement among raft nodes before linearized reading' (duration: 140.450188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:54:31.428658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.699948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-05-09T16:54:31.428763Z","caller":"traceutil/trace.go:171","msg":"trace[1043096106] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2137; }","duration":"102.887932ms","start":"2026-05-09T16:54:31.325851Z","end":"2026-05-09T16:54:31.428739Z","steps":["trace[1043096106] 'agreement among raft nodes before linearized reading' (duration: 102.581647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:54:31.428688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.800171ms","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-05-09T16:54:31.429022Z","caller":"traceutil/trace.go:171","msg":"trace[1233245657] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2137; }","duration":"139.177328ms","start":"2026-05-09T16:54:31.289824Z","end":"2026-05-09T16:54:31.429001Z","steps":["trace[1233245657] 'agreement among raft nodes before linearized reading' (duration: 138.598008ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:54:31.634249Z","caller":"traceutil/trace.go:171","msg":"trace[964688727] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"201.520274ms","start":"2026-05-09T16:54:31.432699Z","end":"2026-05-09T16:54:31.634219Z","steps":["trace[964688727] 'process raft request' (duration: 197.424798ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:54:31.634275Z","caller":"traceutil/trace.go:171","msg":"trace[1483349155] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"201.254651ms","start":"2026-05-09T16:54:31.432996Z","end":"2026-05-09T16:54:31.634251Z","steps":["trace[1483349155] 'process raft request' (duration: 201.110349ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:54:31.634724Z","caller":"traceutil/trace.go:171","msg":"trace[1773345505] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"199.065306ms","start":"2026-05-09T16:54:31.435645Z","end":"2026-05-09T16:54:31.634711Z","steps":["trace[1773345505] 'process raft request' (duration: 198.929873ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:54:31.634902Z","caller":"traceutil/trace.go:171","msg":"trace[1245253466] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"201.818669ms","start":"2026-05-09T16:54:31.433053Z","end":"2026-05-09T16:54:31.634872Z","steps":["trace[1245253466] 'process raft request' (duration: 201.158788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:55:16.103045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.89564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-05-09T16:55:16.103113Z","caller":"traceutil/trace.go:171","msg":"trace[304149146] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2543; }","duration":"145.029891ms","start":"2026-05-09T16:55:15.958069Z","end":"2026-05-09T16:55:16.103099Z","steps":["trace[304149146] 'range keys from in-memory index tree' (duration: 144.729508ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:55:16.808085Z","caller":"traceutil/trace.go:171","msg":"trace[1740189441] linearizableReadLoop","detail":"{readStateIndex:2641; appliedIndex:2640; }","duration":"306.701822ms","start":"2026-05-09T16:55:16.501362Z","end":"2026-05-09T16:55:16.808064Z","steps":["trace[1740189441] 'read index received' (duration: 306.50845ms)","trace[1740189441] 'applied index is now lower than readState.Index' (duration: 192.482µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:55:16.808379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.992516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1296"} {"level":"info","ts":"2026-05-09T16:55:16.808431Z","caller":"traceutil/trace.go:171","msg":"trace[242179719] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2548; }","duration":"307.097148ms","start":"2026-05-09T16:55:16.501319Z","end":"2026-05-09T16:55:16.808417Z","steps":["trace[242179719] 'agreement among raft nodes before linearized reading' (duration: 306.841995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:55:16.808472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:55:16.501301Z","time spent":"307.162249ms","remote":"127.0.0.1:57854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1318,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-05-09T16:55:16.809268Z","caller":"traceutil/trace.go:171","msg":"trace[1727784890] transaction","detail":"{read_only:false; response_revision:2548; number_of_response:1; }","duration":"461.765333ms","start":"2026-05-09T16:55:16.347465Z","end":"2026-05-09T16:55:16.80923Z","steps":["trace[1727784890] 'process raft request' (duration: 460.477565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:55:16.809433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:55:16.34745Z","time spent":"461.877515ms","remote":"127.0.0.1:57768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:55:44.611275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.218633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:55:44.611366Z","caller":"traceutil/trace.go:171","msg":"trace[1066582497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2740; }","duration":"243.367665ms","start":"2026-05-09T16:55:44.36798Z","end":"2026-05-09T16:55:44.611347Z","steps":["trace[1066582497] 'range keys from in-memory index tree' (duration: 243.053451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:55:44.893954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.814387ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-09T16:55:44.894055Z","caller":"traceutil/trace.go:171","msg":"trace[1911756475] linearizableReadLoop","detail":"{readStateIndex:2845; appliedIndex:2844; }","duration":"498.571617ms","start":"2026-05-09T16:55:44.395465Z","end":"2026-05-09T16:55:44.894036Z","steps":["trace[1911756475] 'read index received' (duration: 243.676009ms)","trace[1911756475] 'applied index is now lower than readState.Index' (duration: 254.894238ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:55:44.894324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.262188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:55:44.894577Z","caller":"traceutil/trace.go:171","msg":"trace[1952701463] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2740; }","duration":"281.524202ms","start":"2026-05-09T16:55:44.613005Z","end":"2026-05-09T16:55:44.89453Z","steps":["trace[1952701463] 'agreement among raft nodes before linearized reading' (duration: 281.260997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:55:44.894333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.41811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1296"} {"level":"warn","ts":"2026-05-09T16:55:44.894338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.855391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-05-09T16:55:44.895158Z","caller":"traceutil/trace.go:171","msg":"trace[1244543598] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2740; }","duration":"499.688332ms","start":"2026-05-09T16:55:44.395451Z","end":"2026-05-09T16:55:44.895139Z","steps":["trace[1244543598] 'agreement among raft nodes before linearized reading' (duration: 498.675809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:55:44.895215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:55:44.395388Z","time spent":"499.810743ms","remote":"127.0.0.1:57768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":681,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-05-09T16:55:44.896427Z","caller":"traceutil/trace.go:171","msg":"trace[1270599810] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2740; }","duration":"393.17165ms","start":"2026-05-09T16:55:44.50181Z","end":"2026-05-09T16:55:44.894981Z","steps":["trace[1270599810] 'agreement among raft nodes before linearized reading' (duration: 392.356629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:55:44.896507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:55:44.50179Z","time spent":"394.69862ms","remote":"127.0.0.1:57854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1318,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-05-09T16:55:45.386396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.563867ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:55:45.386941Z","caller":"traceutil/trace.go:171","msg":"trace[296599972] linearizableReadLoop","detail":"{readStateIndex:2847; appliedIndex:2846; }","duration":"464.761081ms","start":"2026-05-09T16:55:44.922147Z","end":"2026-05-09T16:55:45.386909Z","steps":["trace[296599972] 'read index received' (duration: 262.643576ms)","trace[296599972] 'applied index is now lower than readState.Index' (duration: 202.115865ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:55:45.387086Z","caller":"traceutil/trace.go:171","msg":"trace[908282432] transaction","detail":"{read_only:false; response_revision:2742; number_of_response:1; }","duration":"468.324287ms","start":"2026-05-09T16:55:44.918731Z","end":"2026-05-09T16:55:45.387055Z","steps":["trace[908282432] 'process raft request' (duration: 266.032721ms)","trace[908282432] 'compare' (duration: 201.215513ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:55:45.387429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.92588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-05-09T16:55:45.388017Z","caller":"traceutil/trace.go:171","msg":"trace[1557409620] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2742; }","duration":"285.544877ms","start":"2026-05-09T16:55:45.102454Z","end":"2026-05-09T16:55:45.387999Z","steps":["trace[1557409620] 'agreement among raft nodes before linearized reading' (duration: 284.907929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:55:45.387164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.011614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:5908"} {"level":"info","ts":"2026-05-09T16:55:45.388233Z","caller":"traceutil/trace.go:171","msg":"trace[1598737270] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:2742; }","duration":"466.128949ms","start":"2026-05-09T16:55:44.922093Z","end":"2026-05-09T16:55:45.388222Z","steps":["trace[1598737270] 'agreement among raft nodes before linearized reading' (duration: 464.959573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:55:45.388273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:55:44.922077Z","time spent":"466.17978ms","remote":"127.0.0.1:58144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":5930,"request content":"key:\"/registry/statefulsets/auth-system/keycloak\" "} {"level":"warn","ts":"2026-05-09T16:55:45.387904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:55:44.918711Z","time spent":"468.540081ms","remote":"127.0.0.1:57768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:55:45.676691Z","caller":"traceutil/trace.go:171","msg":"trace[1432446451] transaction","detail":"{read_only:false; response_revision:2743; number_of_response:1; }","duration":"279.772692ms","start":"2026-05-09T16:55:45.396891Z","end":"2026-05-09T16:55:45.676664Z","steps":["trace[1432446451] 'process raft request' (duration: 279.575238ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:55:45.677472Z","caller":"traceutil/trace.go:171","msg":"trace[1792097071] transaction","detail":"{read_only:false; response_revision:2745; number_of_response:1; }","duration":"178.38528ms","start":"2026-05-09T16:55:45.499064Z","end":"2026-05-09T16:55:45.677449Z","steps":["trace[1792097071] 'process raft request' (duration: 178.333219ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:55:45.677775Z","caller":"traceutil/trace.go:171","msg":"trace[2751949] transaction","detail":"{read_only:false; response_revision:2744; number_of_response:1; }","duration":"279.746031ms","start":"2026-05-09T16:55:45.397965Z","end":"2026-05-09T16:55:45.677711Z","steps":["trace[2751949] 'process raft request' (duration: 279.326076ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:56:30.371345Z","caller":"traceutil/trace.go:171","msg":"trace[1509444613] transaction","detail":"{read_only:false; response_revision:3137; number_of_response:1; }","duration":"100.094229ms","start":"2026-05-09T16:56:30.271223Z","end":"2026-05-09T16:56:30.371317Z","steps":["trace[1509444613] 'process raft request' (duration: 95.537352ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:58:02.164043Z","caller":"traceutil/trace.go:171","msg":"trace[844688815] transaction","detail":"{read_only:false; response_revision:4250; number_of_response:1; }","duration":"131.842402ms","start":"2026-05-09T16:58:02.032175Z","end":"2026-05-09T16:58:02.164017Z","steps":["trace[844688815] 'process raft request' (duration: 131.235405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:06.478167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.09975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-09T16:58:06.478276Z","caller":"traceutil/trace.go:171","msg":"trace[923798112] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4333; }","duration":"111.260915ms","start":"2026-05-09T16:58:06.366989Z","end":"2026-05-09T16:58:06.47825Z","steps":["trace[923798112] 'agreement among raft nodes before linearized reading' (duration: 110.989357ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:58:07.767553Z","caller":"traceutil/trace.go:171","msg":"trace[842691503] linearizableReadLoop","detail":"{readStateIndex:4573; appliedIndex:4572; }","duration":"400.16106ms","start":"2026-05-09T16:58:07.367366Z","end":"2026-05-09T16:58:07.767527Z","steps":["trace[842691503] 'read index received' (duration: 400.037397ms)","trace[842691503] 'applied index is now lower than readState.Index' (duration: 122.683µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:58:07.767628Z","caller":"traceutil/trace.go:171","msg":"trace[2125325519] transaction","detail":"{read_only:false; response_revision:4383; number_of_response:1; }","duration":"434.709786ms","start":"2026-05-09T16:58:07.332887Z","end":"2026-05-09T16:58:07.767597Z","steps":["trace[2125325519] 'process raft request' (duration: 434.49849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:07.767909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.888548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-05-09T16:58:07.767961Z","caller":"traceutil/trace.go:171","msg":"trace[1400911605] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4383; }","duration":"371.97204ms","start":"2026-05-09T16:58:07.395972Z","end":"2026-05-09T16:58:07.767944Z","steps":["trace[1400911605] 'agreement among raft nodes before linearized reading' (duration: 371.834016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:07.768006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:07.395952Z","time spent":"372.044921ms","remote":"127.0.0.1:57768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":667,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-09T16:58:07.767762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.383045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:58:07.768149Z","caller":"traceutil/trace.go:171","msg":"trace[765254730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4383; }","duration":"400.805688ms","start":"2026-05-09T16:58:07.367311Z","end":"2026-05-09T16:58:07.768117Z","steps":["trace[765254730] 'agreement among raft nodes before linearized reading' (duration: 400.388816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:07.768251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:07.367291Z","time spent":"400.924902ms","remote":"127.0.0.1:57630","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-05-09T16:58:07.768187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:07.332868Z","time spent":"434.985893ms","remote":"127.0.0.1:35790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7567,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:58:07.76875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.630186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2349491"} {"level":"info","ts":"2026-05-09T16:58:07.76888Z","caller":"traceutil/trace.go:171","msg":"trace[715217897] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4383; }","duration":"213.80198ms","start":"2026-05-09T16:58:07.555057Z","end":"2026-05-09T16:58:07.768858Z","steps":["trace[715217897] 'agreement among raft nodes before linearized reading' (duration: 212.834254ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:58:08.110328Z","caller":"traceutil/trace.go:171","msg":"trace[532332436] linearizableReadLoop","detail":"{readStateIndex:4574; appliedIndex:4573; }","duration":"314.870866ms","start":"2026-05-09T16:58:07.795401Z","end":"2026-05-09T16:58:08.110272Z","steps":["trace[532332436] 'read index received' (duration: 314.752313ms)","trace[532332436] 'applied index is now lower than readState.Index' (duration: 117.933µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:58:08.110439Z","caller":"traceutil/trace.go:171","msg":"trace[212574475] transaction","detail":"{read_only:false; response_revision:4384; number_of_response:1; }","duration":"335.442598ms","start":"2026-05-09T16:58:07.774977Z","end":"2026-05-09T16:58:08.110419Z","steps":["trace[212574475] 'process raft request' (duration: 335.201642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:08.110708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.246555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-05-09T16:58:08.110784Z","caller":"traceutil/trace.go:171","msg":"trace[140401701] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4384; }","duration":"315.404079ms","start":"2026-05-09T16:58:07.79536Z","end":"2026-05-09T16:58:08.110764Z","steps":["trace[140401701] 'agreement among raft nodes before linearized reading' (duration: 315.138602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:08.110825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:07.795344Z","time spent":"315.472382ms","remote":"127.0.0.1:57752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1839,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"warn","ts":"2026-05-09T16:58:08.110821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:07.774954Z","time spent":"335.535681ms","remote":"127.0.0.1:57768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:58:08.393414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.15795ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:58:08.393562Z","caller":"traceutil/trace.go:171","msg":"trace[604454961] transaction","detail":"{read_only:false; response_revision:4385; number_of_response:1; }","duration":"414.794813ms","start":"2026-05-09T16:58:07.978731Z","end":"2026-05-09T16:58:08.393526Z","steps":["trace[604454961] 'process raft request' (duration: 266.453758ms)","trace[604454961] 'compare' (duration: 147.985716ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:58:08.393735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:07.978718Z","time spent":"414.941278ms","remote":"127.0.0.1:57738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":906,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T16:58:08.393877Z","caller":"traceutil/trace.go:171","msg":"trace[1228192354] linearizableReadLoop","detail":"{readStateIndex:4575; appliedIndex:4574; }","duration":"283.482351ms","start":"2026-05-09T16:58:08.110377Z","end":"2026-05-09T16:58:08.393859Z","steps":["trace[1228192354] 'read index received' (duration: 134.850407ms)","trace[1228192354] 'applied index is now lower than readState.Index' (duration: 148.630304ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:58:08.394135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.074801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-09T16:58:08.394226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.369106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-pmmzv\" ","response":"range_response_count:1 size:9757"} {"level":"warn","ts":"2026-05-09T16:58:08.394242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.309205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T16:58:08.394272Z","caller":"traceutil/trace.go:171","msg":"trace[103515625] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4386; }","duration":"370.268727ms","start":"2026-05-09T16:58:08.02398Z","end":"2026-05-09T16:58:08.394248Z","steps":["trace[103515625] 'agreement among raft nodes before linearized reading' (duration: 370.00963ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:58:08.39429Z","caller":"traceutil/trace.go:171","msg":"trace[1990262884] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:4386; }","duration":"590.399008ms","start":"2026-05-09T16:58:07.803879Z","end":"2026-05-09T16:58:08.394278Z","steps":["trace[1990262884] 'agreement among raft nodes before linearized reading' (duration: 590.314315ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:58:08.394291Z","caller":"traceutil/trace.go:171","msg":"trace[229764369] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-pmmzv; range_end:; response_count:1; response_revision:4386; }","duration":"416.432307ms","start":"2026-05-09T16:58:07.977835Z","end":"2026-05-09T16:58:08.394268Z","steps":["trace[229764369] 'agreement among raft nodes before linearized reading' (duration: 416.233602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:08.394335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:07.803824Z","time spent":"590.502341ms","remote":"127.0.0.1:57944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":29,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-05-09T16:58:08.394347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:08.023966Z","time spent":"370.36286ms","remote":"127.0.0.1:49906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-09T16:58:08.394419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:07.977777Z","time spent":"416.624194ms","remote":"127.0.0.1:57840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":9779,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-pmmzv\" "} {"level":"warn","ts":"2026-05-09T16:58:08.394616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.54101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T16:58:08.394701Z","caller":"traceutil/trace.go:171","msg":"trace[1245041907] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:4386; }","duration":"280.802517ms","start":"2026-05-09T16:58:08.113882Z","end":"2026-05-09T16:58:08.394685Z","steps":["trace[1245041907] 'agreement among raft nodes before linearized reading' (duration: 280.458867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:08.394733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.349254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3465"} {"level":"info","ts":"2026-05-09T16:58:08.394827Z","caller":"traceutil/trace.go:171","msg":"trace[93369527] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4386; }","duration":"262.452177ms","start":"2026-05-09T16:58:08.132335Z","end":"2026-05-09T16:58:08.394787Z","steps":["trace[93369527] 'agreement among raft nodes before linearized reading' (duration: 261.915112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:08.39504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.163946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-05-09T16:58:08.395085Z","caller":"traceutil/trace.go:171","msg":"trace[1066977469] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4386; }","duration":"281.232919ms","start":"2026-05-09T16:58:08.113841Z","end":"2026-05-09T16:58:08.395074Z","steps":["trace[1066977469] 'agreement among raft nodes before linearized reading' (duration: 280.383895ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:58:08.646698Z","caller":"traceutil/trace.go:171","msg":"trace[87737920] linearizableReadLoop","detail":"{readStateIndex:4578; appliedIndex:4577; }","duration":"192.253953ms","start":"2026-05-09T16:58:08.45439Z","end":"2026-05-09T16:58:08.646644Z","steps":["trace[87737920] 'read index received' (duration: 191.992155ms)","trace[87737920] 'applied index is now lower than readState.Index' (duration: 260.998µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:58:08.646901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.501409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-05-09T16:58:08.646909Z","caller":"traceutil/trace.go:171","msg":"trace[825831123] transaction","detail":"{read_only:false; response_revision:4388; number_of_response:1; }","duration":"243.227703ms","start":"2026-05-09T16:58:08.403666Z","end":"2026-05-09T16:58:08.646894Z","steps":["trace[825831123] 'process raft request' (duration: 242.621416ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:58:08.646943Z","caller":"traceutil/trace.go:171","msg":"trace[812021380] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4388; }","duration":"192.588022ms","start":"2026-05-09T16:58:08.454344Z","end":"2026-05-09T16:58:08.646932Z","steps":["trace[812021380] 'agreement among raft nodes before linearized reading' (duration: 192.431067ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:58:08.673012Z","caller":"traceutil/trace.go:171","msg":"trace[1361846410] transaction","detail":"{read_only:false; response_revision:4389; number_of_response:1; }","duration":"196.762915ms","start":"2026-05-09T16:58:08.476213Z","end":"2026-05-09T16:58:08.672976Z","steps":["trace[1361846410] 'process raft request' (duration: 196.601651ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:58:09.751021Z","caller":"traceutil/trace.go:171","msg":"trace[650484622] linearizableReadLoop","detail":"{readStateIndex:4593; appliedIndex:4592; }","duration":"393.669818ms","start":"2026-05-09T16:58:09.357327Z","end":"2026-05-09T16:58:09.750997Z","steps":["trace[650484622] 'read index received' (duration: 393.573535ms)","trace[650484622] 'applied index is now lower than readState.Index' (duration: 94.713µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:58:09.751034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:09.343886Z","time spent":"407.142703ms","remote":"127.0.0.1:57668","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-09T16:58:09.751169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.815931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T16:58:09.75123Z","caller":"traceutil/trace.go:171","msg":"trace[1840879169] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:4402; }","duration":"393.904794ms","start":"2026-05-09T16:58:09.357309Z","end":"2026-05-09T16:58:09.751214Z","steps":["trace[1840879169] 'agreement among raft nodes before linearized reading' (duration: 393.79056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:09.751281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:09.3573Z","time spent":"393.971517ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":29,"request content":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true "} {"level":"info","ts":"2026-05-09T16:58:09.76702Z","caller":"traceutil/trace.go:171","msg":"trace[989140027] transaction","detail":"{read_only:false; response_revision:4403; number_of_response:1; }","duration":"149.329138ms","start":"2026-05-09T16:58:09.617674Z","end":"2026-05-09T16:58:09.767003Z","steps":["trace[989140027] 'process raft request' (duration: 146.982824ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:09.767669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.127173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:58:09.767841Z","caller":"traceutil/trace.go:171","msg":"trace[749361080] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4405; }","duration":"400.34827ms","start":"2026-05-09T16:58:09.367465Z","end":"2026-05-09T16:58:09.767814Z","steps":["trace[749361080] 'agreement among raft nodes before linearized reading' (duration: 399.98349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:09.767971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:09.367448Z","time spent":"400.508984ms","remote":"127.0.0.1:57646","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-05-09T16:58:15.296021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.182099ms","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-05-09T16:58:15.296123Z","caller":"traceutil/trace.go:171","msg":"trace[343859205] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4449; }","duration":"380.365124ms","start":"2026-05-09T16:58:14.915733Z","end":"2026-05-09T16:58:15.296098Z","steps":["trace[343859205] 'range keys from in-memory index tree' (duration: 379.929981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:15.296186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:14.915705Z","time spent":"380.467877ms","remote":"127.0.0.1:57944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-09T16:58:15.296553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.559741ms","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-05-09T16:58:15.296649Z","caller":"traceutil/trace.go:171","msg":"trace[1656523447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4449; }","duration":"431.687226ms","start":"2026-05-09T16:58:14.864934Z","end":"2026-05-09T16:58:15.296621Z","steps":["trace[1656523447] 'range keys from in-memory index tree' (duration: 431.381287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:15.296706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:14.864904Z","time spent":"431.791598ms","remote":"127.0.0.1:57944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T16:58:15.296789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.219159ms","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-05-09T16:58:15.296911Z","caller":"traceutil/trace.go:171","msg":"trace[1778789589] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4449; }","duration":"499.397383ms","start":"2026-05-09T16:58:14.797476Z","end":"2026-05-09T16:58:15.296873Z","steps":["trace[1778789589] 'range keys from in-memory index tree' (duration: 498.930921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:15.297003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.649273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-09T16:58:15.297054Z","caller":"traceutil/trace.go:171","msg":"trace[1831983160] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4449; }","duration":"477.754115ms","start":"2026-05-09T16:58:14.819286Z","end":"2026-05-09T16:58:15.29704Z","steps":["trace[1831983160] 'range keys from in-memory index tree' (duration: 477.465868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:15.29706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.179196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"warn","ts":"2026-05-09T16:58:15.29708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:14.797456Z","time spent":"499.489126ms","remote":"127.0.0.1:57944","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-05-09T16:58:15.297093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:14.819232Z","time spent":"477.852757ms","remote":"127.0.0.1:57944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-09T16:58:15.297101Z","caller":"traceutil/trace.go:171","msg":"trace[306085649] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4449; }","duration":"189.281918ms","start":"2026-05-09T16:58:15.10781Z","end":"2026-05-09T16:58:15.297092Z","steps":["trace[306085649] 'range keys from in-memory index tree' (duration: 188.976931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:15.296587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.839153ms","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-05-09T16:58:15.297261Z","caller":"traceutil/trace.go:171","msg":"trace[1144646635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4449; }","duration":"388.529132ms","start":"2026-05-09T16:58:14.90871Z","end":"2026-05-09T16:58:15.297239Z","steps":["trace[1144646635] 'range keys from in-memory index tree' (duration: 387.719049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:15.297318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:14.908695Z","time spent":"388.607915ms","remote":"127.0.0.1:57944","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":"warn","ts":"2026-05-09T16:58:15.297305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.148772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"info","ts":"2026-05-09T16:58:15.297404Z","caller":"traceutil/trace.go:171","msg":"trace[981236456] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4449; }","duration":"261.259996ms","start":"2026-05-09T16:58:15.03611Z","end":"2026-05-09T16:58:15.29737Z","steps":["trace[981236456] 'range keys from in-memory index tree' (duration: 260.981368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:15.297711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.59854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-6766d46944-47cmg.18adf42b24edc5f3\" ","response":"range_response_count:1 size:896"} {"level":"info","ts":"2026-05-09T16:58:15.2978Z","caller":"traceutil/trace.go:171","msg":"trace[1926118362] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-6766d46944-47cmg.18adf42b24edc5f3; range_end:; response_count:1; response_revision:4449; }","duration":"262.710344ms","start":"2026-05-09T16:58:15.035074Z","end":"2026-05-09T16:58:15.297784Z","steps":["trace[1926118362] 'range keys from in-memory index tree' (duration: 262.519099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:15.298091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.211514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-09T16:58:15.298148Z","caller":"traceutil/trace.go:171","msg":"trace[1423694470] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4449; }","duration":"264.306207ms","start":"2026-05-09T16:58:15.033831Z","end":"2026-05-09T16:58:15.298137Z","steps":["trace[1423694470] 'range keys from in-memory index tree' (duration: 262.076997ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:58:15.730134Z","caller":"traceutil/trace.go:171","msg":"trace[2109715904] transaction","detail":"{read_only:false; response_revision:4466; number_of_response:1; }","duration":"248.63348ms","start":"2026-05-09T16:58:15.481452Z","end":"2026-05-09T16:58:15.730086Z","steps":["trace[2109715904] 'process raft request' (duration: 182.682813ms)","trace[2109715904] 'compare' (duration: 65.620217ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:58:15.730184Z","caller":"traceutil/trace.go:171","msg":"trace[1219028751] transaction","detail":"{read_only:false; response_revision:4469; number_of_response:1; }","duration":"246.620685ms","start":"2026-05-09T16:58:15.483528Z","end":"2026-05-09T16:58:15.730149Z","steps":["trace[1219028751] 'process raft request' (duration: 246.564274ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:58:15.730659Z","caller":"traceutil/trace.go:171","msg":"trace[231227913] transaction","detail":"{read_only:false; response_revision:4468; number_of_response:1; }","duration":"248.069524ms","start":"2026-05-09T16:58:15.482575Z","end":"2026-05-09T16:58:15.730645Z","steps":["trace[231227913] 'process raft request' (duration: 247.440968ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:58:15.730828Z","caller":"traceutil/trace.go:171","msg":"trace[696279294] transaction","detail":"{read_only:false; response_revision:4467; number_of_response:1; }","duration":"249.234456ms","start":"2026-05-09T16:58:15.481574Z","end":"2026-05-09T16:58:15.730808Z","steps":["trace[696279294] 'process raft request' (duration: 248.365102ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:58:19.178968Z","caller":"traceutil/trace.go:171","msg":"trace[1949852998] transaction","detail":"{read_only:false; response_revision:4562; number_of_response:1; }","duration":"105.602994ms","start":"2026-05-09T16:58:19.073306Z","end":"2026-05-09T16:58:19.178909Z","steps":["trace[1949852998] 'process raft request' (duration: 105.430299ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:58:19.182756Z","caller":"traceutil/trace.go:171","msg":"trace[188813019] transaction","detail":"{read_only:false; response_revision:4563; number_of_response:1; }","duration":"107.588056ms","start":"2026-05-09T16:58:19.075145Z","end":"2026-05-09T16:58:19.182733Z","steps":["trace[188813019] 'process raft request' (duration: 106.357124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:19.68221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.053444ms","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-05-09T16:58:19.682293Z","caller":"traceutil/trace.go:171","msg":"trace[1633188195] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4570; }","duration":"321.171137ms","start":"2026-05-09T16:58:19.361104Z","end":"2026-05-09T16:58:19.682275Z","steps":["trace[1633188195] 'range keys from in-memory index tree' (duration: 320.918441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:19.682334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:19.361095Z","time spent":"321.229899ms","remote":"127.0.0.1:57944","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-05-09T16:58:19.682325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.485248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-09T16:58:19.682434Z","caller":"traceutil/trace.go:171","msg":"trace[113335178] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4570; }","duration":"323.639771ms","start":"2026-05-09T16:58:19.358771Z","end":"2026-05-09T16:58:19.68241Z","steps":["trace[113335178] 'range keys from in-memory index tree' (duration: 323.281932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:19.682497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:19.358748Z","time spent":"323.735534ms","remote":"127.0.0.1:57944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-09T16:58:19.682537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.5434ms","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-05-09T16:58:19.682483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.881201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-45e66ee6-34ce-4a19-b3d5-4bcf97324cd3\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-09T16:58:19.682607Z","caller":"traceutil/trace.go:171","msg":"trace[1139923257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4570; }","duration":"320.637402ms","start":"2026-05-09T16:58:19.361951Z","end":"2026-05-09T16:58:19.682588Z","steps":["trace[1139923257] 'range keys from in-memory index tree' (duration: 320.397147ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:58:19.682627Z","caller":"traceutil/trace.go:171","msg":"trace[2064129681] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-45e66ee6-34ce-4a19-b3d5-4bcf97324cd3; range_end:; response_count:1; response_revision:4570; }","duration":"259.057855ms","start":"2026-05-09T16:58:19.423551Z","end":"2026-05-09T16:58:19.682609Z","steps":["trace[2064129681] 'range keys from in-memory index tree' (duration: 258.739128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:19.682651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:19.361942Z","time spent":"320.699904ms","remote":"127.0.0.1:57944","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":"warn","ts":"2026-05-09T16:58:19.682714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.464234ms","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-05-09T16:58:19.682751Z","caller":"traceutil/trace.go:171","msg":"trace[672995087] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4570; }","duration":"316.517335ms","start":"2026-05-09T16:58:19.36622Z","end":"2026-05-09T16:58:19.682737Z","steps":["trace[672995087] 'range keys from in-memory index tree' (duration: 316.341659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:19.682609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.265316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-09T16:58:19.682779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:19.36621Z","time spent":"316.562207ms","remote":"127.0.0.1:57944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-09T16:58:19.682804Z","caller":"traceutil/trace.go:171","msg":"trace[309410194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4570; }","duration":"322.482811ms","start":"2026-05-09T16:58:19.360309Z","end":"2026-05-09T16:58:19.682792Z","steps":["trace[309410194] 'range keys from in-memory index tree' (duration: 322.165692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:19.682835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:19.360297Z","time spent":"322.529952ms","remote":"127.0.0.1:57944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T16:58:19.682891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.920508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-05-09T16:58:19.682914Z","caller":"traceutil/trace.go:171","msg":"trace[1974972961] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4570; }","duration":"318.979879ms","start":"2026-05-09T16:58:19.363926Z","end":"2026-05-09T16:58:19.682906Z","steps":["trace[1974972961] 'range keys from in-memory index tree' (duration: 318.813796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:19.682938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:19.363907Z","time spent":"319.025291ms","remote":"127.0.0.1:57944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":547,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-05-09T16:58:19.682984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.35498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-09T16:58:19.683013Z","caller":"traceutil/trace.go:171","msg":"trace[1545006199] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:1; response_revision:4570; }","duration":"340.408852ms","start":"2026-05-09T16:58:19.342596Z","end":"2026-05-09T16:58:19.683005Z","steps":["trace[1545006199] 'range keys from in-memory index tree' (duration: 340.242608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:19.683038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:19.342582Z","time spent":"340.450532ms","remote":"127.0.0.1:57668","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.223\" "} {"level":"info","ts":"2026-05-09T16:58:19.685347Z","caller":"traceutil/trace.go:171","msg":"trace[1578966728] transaction","detail":"{read_only:false; response_revision:4571; number_of_response:1; }","duration":"106.753514ms","start":"2026-05-09T16:58:19.578576Z","end":"2026-05-09T16:58:19.685329Z","steps":["trace[1578966728] 'process raft request' (duration: 106.61067ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:58:22.083108Z","caller":"traceutil/trace.go:171","msg":"trace[1377163706] linearizableReadLoop","detail":"{readStateIndex:4782; appliedIndex:4781; }","duration":"381.691854ms","start":"2026-05-09T16:58:21.701399Z","end":"2026-05-09T16:58:22.083091Z","steps":["trace[1377163706] 'read index received' (duration: 381.53921ms)","trace[1377163706] 'applied index is now lower than readState.Index' (duration: 151.684µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:58:22.083426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.858029ms","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-05-09T16:58:22.083462Z","caller":"traceutil/trace.go:171","msg":"trace[889976774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4586; }","duration":"381.923461ms","start":"2026-05-09T16:58:21.701529Z","end":"2026-05-09T16:58:22.083453Z","steps":["trace[889976774] 'agreement among raft nodes before linearized reading' (duration: 381.794018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:22.083484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:21.701523Z","time spent":"381.955992ms","remote":"127.0.0.1:57944","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":"warn","ts":"2026-05-09T16:58:22.083643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.887037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-05-09T16:58:22.083694Z","caller":"traceutil/trace.go:171","msg":"trace[1441720704] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4586; }","duration":"246.998389ms","start":"2026-05-09T16:58:21.836687Z","end":"2026-05-09T16:58:22.083685Z","steps":["trace[1441720704] 'agreement among raft nodes before linearized reading' (duration: 246.857335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:22.083752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.347925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"warn","ts":"2026-05-09T16:58:22.083856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.105418ms","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-05-09T16:58:22.083903Z","caller":"traceutil/trace.go:171","msg":"trace[1721975603] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4586; }","duration":"230.53157ms","start":"2026-05-09T16:58:21.853345Z","end":"2026-05-09T16:58:22.083877Z","steps":["trace[1721975603] 'agreement among raft nodes before linearized reading' (duration: 230.15507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:22.083917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.110798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-05-09T16:58:22.083974Z","caller":"traceutil/trace.go:171","msg":"trace[1034387786] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4586; }","duration":"381.1831ms","start":"2026-05-09T16:58:21.702775Z","end":"2026-05-09T16:58:22.083958Z","steps":["trace[1034387786] 'agreement among raft nodes before linearized reading' (duration: 381.009316ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:58:22.083973Z","caller":"traceutil/trace.go:171","msg":"trace[1795703034] transaction","detail":"{read_only:false; response_revision:4586; number_of_response:1; }","duration":"385.483723ms","start":"2026-05-09T16:58:21.698481Z","end":"2026-05-09T16:58:22.083965Z","steps":["trace[1795703034] 'process raft request' (duration: 384.503788ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:58:22.083924Z","caller":"traceutil/trace.go:171","msg":"trace[245069208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4586; }","duration":"381.19152ms","start":"2026-05-09T16:58:21.702718Z","end":"2026-05-09T16:58:22.08391Z","steps":["trace[245069208] 'agreement among raft nodes before linearized reading' (duration: 380.978035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:22.084033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:21.70277Z","time spent":"381.249773ms","remote":"127.0.0.1:57944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":547,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-05-09T16:58:22.084047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.615728ms","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-05-09T16:58:22.084131Z","caller":"traceutil/trace.go:171","msg":"trace[548856778] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4586; }","duration":"382.723491ms","start":"2026-05-09T16:58:21.701391Z","end":"2026-05-09T16:58:22.084115Z","steps":["trace[548856778] 'agreement among raft nodes before linearized reading' (duration: 382.470964ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:22.084067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:21.702647Z","time spent":"381.411977ms","remote":"127.0.0.1:57944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T16:58:22.084163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:21.70133Z","time spent":"382.827083ms","remote":"127.0.0.1:57944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-09T16:58:22.084294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:21.698461Z","time spent":"385.577745ms","remote":"127.0.0.1:57944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:58:22.084316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.520789ms","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-05-09T16:58:22.084471Z","caller":"traceutil/trace.go:171","msg":"trace[1682756492] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4586; }","duration":"381.706344ms","start":"2026-05-09T16:58:21.702747Z","end":"2026-05-09T16:58:22.084454Z","steps":["trace[1682756492] 'agreement among raft nodes before linearized reading' (duration: 380.856012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:22.084614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:21.702741Z","time spent":"381.853008ms","remote":"127.0.0.1:57944","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-05-09T16:58:22.343117Z","caller":"traceutil/trace.go:171","msg":"trace[887377629] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4595; }","duration":"155.353811ms","start":"2026-05-09T16:58:22.187736Z","end":"2026-05-09T16:58:22.343089Z","steps":["trace[887377629] 'process raft request' (duration: 124.698003ms)","trace[887377629] 'compare' (duration: 30.608725ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:58:30.850894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.271451ms","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-05-09T16:58:30.851075Z","caller":"traceutil/trace.go:171","msg":"trace[1018200138] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:4725; }","duration":"152.517997ms","start":"2026-05-09T16:58:30.698535Z","end":"2026-05-09T16:58:30.851053Z","steps":["trace[1018200138] 'count revisions from in-memory index tree' (duration: 152.176198ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:59:07.22593Z","caller":"traceutil/trace.go:171","msg":"trace[1932423521] transaction","detail":"{read_only:false; response_revision:5102; number_of_response:1; }","duration":"164.859936ms","start":"2026-05-09T16:59:07.061038Z","end":"2026-05-09T16:59:07.225897Z","steps":["trace[1932423521] 'process raft request' (duration: 158.43039ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:59:08.48647Z","caller":"traceutil/trace.go:171","msg":"trace[1851640323] transaction","detail":"{read_only:false; response_revision:5107; number_of_response:1; }","duration":"108.478156ms","start":"2026-05-09T16:59:08.377964Z","end":"2026-05-09T16:59:08.486442Z","steps":["trace[1851640323] 'process raft request' (duration: 96.848981ms)","trace[1851640323] 'compare' (duration: 11.437391ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:59:08.486836Z","caller":"traceutil/trace.go:171","msg":"trace[676609759] transaction","detail":"{read_only:false; response_revision:5108; number_of_response:1; }","duration":"108.760102ms","start":"2026-05-09T16:59:08.378056Z","end":"2026-05-09T16:59:08.486816Z","steps":["trace[676609759] 'process raft request' (duration: 108.343473ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:59:08.486877Z","caller":"traceutil/trace.go:171","msg":"trace[1527931732] transaction","detail":"{read_only:false; response_revision:5109; number_of_response:1; }","duration":"108.409303ms","start":"2026-05-09T16:59:08.378445Z","end":"2026-05-09T16:59:08.486854Z","steps":["trace[1527931732] 'process raft request' (duration: 108.206919ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:59:08.495519Z","caller":"traceutil/trace.go:171","msg":"trace[1027790707] transaction","detail":"{read_only:false; response_revision:5110; number_of_response:1; }","duration":"106.181054ms","start":"2026-05-09T16:59:08.389319Z","end":"2026-05-09T16:59:08.4955Z","steps":["trace[1027790707] 'process raft request' (duration: 106.061121ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:01:35.28752Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3171} {"level":"info","ts":"2026-05-09T17:01:35.37636Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3171,"took":"87.66805ms","hash":1950770432,"current-db-size-bytes":57626624,"current-db-size":"58 MB","current-db-size-in-use-bytes":31383552,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-09T17:01:35.376449Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1950770432,"revision":3171,"compact-revision":-1} {"level":"info","ts":"2026-05-09T17:01:55.841965Z","caller":"traceutil/trace.go:171","msg":"trace[1062965842] transaction","detail":"{read_only:false; response_revision:6524; number_of_response:1; }","duration":"135.470841ms","start":"2026-05-09T17:01:55.706466Z","end":"2026-05-09T17:01:55.841937Z","steps":["trace[1062965842] 'process raft request' (duration: 135.316549ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:02:20.895436Z","caller":"traceutil/trace.go:171","msg":"trace[1486564486] transaction","detail":"{read_only:false; response_revision:6741; number_of_response:1; }","duration":"114.301806ms","start":"2026-05-09T17:02:20.781114Z","end":"2026-05-09T17:02:20.895415Z","steps":["trace[1486564486] 'process raft request' (duration: 109.875131ms)"],"step_count":1}