{"level":"warn","ts":"2026-06-09T17:00:14.724263Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-09T17:00:14.724375Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.179:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.179:2380","--initial-cluster=instance=https://199.19.213.179:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.179:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.179: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-06-09T17:00:14.724456Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-09T17:00:14.724472Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.179:2380"]} {"level":"info","ts":"2026-06-09T17:00:14.724493Z","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-06-09T17:00:14.725291Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.179:2379"]} {"level":"info","ts":"2026-06-09T17:00:14.725394Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.179:2380"],"listen-peer-urls":["https://199.19.213.179:2380"],"advertise-client-urls":["https://199.19.213.179:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.179:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.179: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-06-09T17:00:14.733025Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.344013ms"} {"level":"info","ts":"2026-06-09T17:00:14.750175Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"69895f7c3b831156","cluster-id":"caac76e5ff14ceff"} {"level":"info","ts":"2026-06-09T17:00:14.750266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 switched to configuration voters=()"} {"level":"info","ts":"2026-06-09T17:00:14.750298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 became follower at term 0"} {"level":"info","ts":"2026-06-09T17:00:14.75032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 69895f7c3b831156 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-09T17:00:14.750334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 became follower at term 1"} {"level":"info","ts":"2026-06-09T17:00:14.750388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 switched to configuration voters=(7604714432970821974)"} {"level":"warn","ts":"2026-06-09T17:00:14.770249Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-09T17:00:14.772992Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-09T17:00:14.774308Z","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-06-09T17:00:14.776725Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"69895f7c3b831156","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-09T17:00:14.777076Z","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-06-09T17:00:14.777214Z","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-06-09T17:00:14.777232Z","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-06-09T17:00:14.777164Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"69895f7c3b831156","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-09T17:00:14.778774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 switched to configuration voters=(7604714432970821974)"} {"level":"info","ts":"2026-06-09T17:00:14.779513Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"caac76e5ff14ceff","local-member-id":"69895f7c3b831156","added-peer-id":"69895f7c3b831156","added-peer-peer-urls":["https://199.19.213.179:2380"]} {"level":"info","ts":"2026-06-09T17:00:14.781388Z","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-06-09T17:00:14.781532Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.179:2380"} {"level":"info","ts":"2026-06-09T17:00:14.781627Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"69895f7c3b831156","initial-advertise-peer-urls":["https://199.19.213.179:2380"],"listen-peer-urls":["https://199.19.213.179:2380"],"advertise-client-urls":["https://199.19.213.179:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.179:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-09T17:00:14.781674Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-09T17:00:14.781642Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.179:2380"} {"level":"info","ts":"2026-06-09T17:00:15.053215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 is starting a new election at term 1"} {"level":"info","ts":"2026-06-09T17:00:15.05328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-09T17:00:15.053302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 received MsgPreVoteResp from 69895f7c3b831156 at term 1"} {"level":"info","ts":"2026-06-09T17:00:15.053317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 became candidate at term 2"} {"level":"info","ts":"2026-06-09T17:00:15.053322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 received MsgVoteResp from 69895f7c3b831156 at term 2"} {"level":"info","ts":"2026-06-09T17:00:15.053331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"69895f7c3b831156 became leader at term 2"} {"level":"info","ts":"2026-06-09T17:00:15.053338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 69895f7c3b831156 elected leader 69895f7c3b831156 at term 2"} {"level":"info","ts":"2026-06-09T17:00:15.060313Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T17:00:15.066236Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"69895f7c3b831156","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.179:2379]}","request-path":"/0/members/69895f7c3b831156/attributes","cluster-id":"caac76e5ff14ceff","publish-timeout":"7s"} {"level":"info","ts":"2026-06-09T17:00:15.066396Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-09T17:00:15.066472Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-09T17:00:15.06694Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"caac76e5ff14ceff","local-member-id":"69895f7c3b831156","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T17:00:15.067094Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T17:00:15.067134Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T17:00:15.069136Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-09T17:00:15.069804Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.179:2379"} {"level":"info","ts":"2026-06-09T17:00:15.06999Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-09T17:00:15.070079Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-09T17:00:39.923627Z","caller":"traceutil/trace.go:171","msg":"trace[1208785182] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"109.966083ms","start":"2026-06-09T17:00:39.813609Z","end":"2026-06-09T17:00:39.923575Z","steps":["trace[1208785182] 'process raft request' (duration: 70.151985ms)","trace[1208785182] 'compare' (duration: 39.622915ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:01:01.125493Z","caller":"traceutil/trace.go:171","msg":"trace[1526303822] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"203.942517ms","start":"2026-06-09T17:01:00.921523Z","end":"2026-06-09T17:01:01.125466Z","steps":["trace[1526303822] 'process raft request' (duration: 199.32508ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:01:01.25572Z","caller":"traceutil/trace.go:171","msg":"trace[1102897333] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"113.687742ms","start":"2026-06-09T17:01:01.142002Z","end":"2026-06-09T17:01:01.255689Z","steps":["trace[1102897333] 'process raft request' (duration: 93.950573ms)","trace[1102897333] 'compare' (duration: 19.626427ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:01:02.183197Z","caller":"traceutil/trace.go:171","msg":"trace[1483556966] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:680; }","duration":"243.246957ms","start":"2026-06-09T17:01:01.939926Z","end":"2026-06-09T17:01:02.183173Z","steps":["trace[1483556966] 'read index received' (duration: 243.127316ms)","trace[1483556966] 'applied index is now lower than readState.Index' (duration: 118.871µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:01:02.183246Z","caller":"traceutil/trace.go:171","msg":"trace[1554979507] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"371.371794ms","start":"2026-06-09T17:01:01.811844Z","end":"2026-06-09T17:01:02.183216Z","steps":["trace[1554979507] 'process raft request' (duration: 371.209332ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:01:02.183409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.45978ms","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-06-09T17:01:02.183454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.148511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-09T17:01:02.183481Z","caller":"traceutil/trace.go:171","msg":"trace[1589252655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:661; }","duration":"243.57957ms","start":"2026-06-09T17:01:01.93989Z","end":"2026-06-09T17:01:02.18347Z","steps":["trace[1589252655] 'agreement among raft nodes before linearized reading' (duration: 243.414549ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:01:02.183509Z","caller":"traceutil/trace.go:171","msg":"trace[732790064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:661; }","duration":"130.324981ms","start":"2026-06-09T17:01:02.053171Z","end":"2026-06-09T17:01:02.183496Z","steps":["trace[732790064] 'agreement among raft nodes before linearized reading' (duration: 130.145139ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:01:02.184193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:01:01.81182Z","time spent":"371.489104ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:01:02.587743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.624744ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:01:02.58798Z","caller":"traceutil/trace.go:171","msg":"trace[1372001643] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"400.629163ms","start":"2026-06-09T17:01:02.187314Z","end":"2026-06-09T17:01:02.587943Z","steps":["trace[1372001643] 'process raft request' (duration: 278.460563ms)","trace[1372001643] 'compare' (duration: 121.302771ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:01:02.588006Z","caller":"traceutil/trace.go:171","msg":"trace[2019473698] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:681; }","duration":"296.823108ms","start":"2026-06-09T17:01:02.291158Z","end":"2026-06-09T17:01:02.587981Z","steps":["trace[2019473698] 'read index received' (duration: 174.599028ms)","trace[2019473698] 'applied index is now lower than readState.Index' (duration: 122.22285ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:01:02.588019Z","caller":"traceutil/trace.go:171","msg":"trace[113445605] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"399.840185ms","start":"2026-06-09T17:01:02.188155Z","end":"2026-06-09T17:01:02.587995Z","steps":["trace[113445605] 'process raft request' (duration: 399.739514ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:01:02.588119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:01:02.187296Z","time spent":"400.743323ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:01:02.588152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.991559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T17:01:02.58815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:01:02.188066Z","time spent":"400.019916ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:01:02.58821Z","caller":"traceutil/trace.go:171","msg":"trace[791447390] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:663; }","duration":"297.077591ms","start":"2026-06-09T17:01:02.291117Z","end":"2026-06-09T17:01:02.588195Z","steps":["trace[791447390] 'agreement among raft nodes before linearized reading' (duration: 296.956489ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:01:17.979714Z","caller":"traceutil/trace.go:171","msg":"trace[140526847] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"190.849585ms","start":"2026-06-09T17:01:17.788834Z","end":"2026-06-09T17:01:17.979683Z","steps":["trace[140526847] 'process raft request' (duration: 190.669593ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:01:44.855758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.96905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-09T17:01:44.855849Z","caller":"traceutil/trace.go:171","msg":"trace[1936782759] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1161; }","duration":"127.126521ms","start":"2026-06-09T17:01:44.728701Z","end":"2026-06-09T17:01:44.855828Z","steps":["trace[1936782759] 'count revisions from in-memory index tree' (duration: 126.881799ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:02:07.338465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.03323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-06-09T17:02:07.338612Z","caller":"traceutil/trace.go:171","msg":"trace[1063403107] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1419; }","duration":"109.226159ms","start":"2026-06-09T17:02:07.229355Z","end":"2026-06-09T17:02:07.338582Z","steps":["trace[1063403107] 'range keys from in-memory index tree' (duration: 108.865504ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:02:08.812643Z","caller":"traceutil/trace.go:171","msg":"trace[165100439] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"249.250156ms","start":"2026-06-09T17:02:08.563321Z","end":"2026-06-09T17:02:08.812571Z","steps":["trace[165100439] 'process raft request' (duration: 248.442543ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:02:33.060113Z","caller":"traceutil/trace.go:171","msg":"trace[253365351] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"106.97796ms","start":"2026-06-09T17:02:32.953095Z","end":"2026-06-09T17:02:33.060073Z","steps":["trace[253365351] 'process raft request' (duration: 106.749222ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:02:33.974445Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1249360413915360453,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-09T17:02:34.061891Z","caller":"traceutil/trace.go:171","msg":"trace[1284872812] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"635.127013ms","start":"2026-06-09T17:02:33.42674Z","end":"2026-06-09T17:02:34.061867Z","steps":["trace[1284872812] 'process raft request' (duration: 634.994708ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:02:34.062016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:02:33.426713Z","time spent":"635.234607ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:02:34.173463Z","caller":"traceutil/trace.go:171","msg":"trace[830328445] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"562.713052ms","start":"2026-06-09T17:02:33.610725Z","end":"2026-06-09T17:02:34.173438Z","steps":["trace[830328445] 'process raft request' (duration: 562.445023ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:02:34.1735Z","caller":"traceutil/trace.go:171","msg":"trace[1451117845] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"637.509211ms","start":"2026-06-09T17:02:33.535971Z","end":"2026-06-09T17:02:34.17348Z","steps":["trace[1451117845] 'process raft request' (duration: 637.097296ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:02:34.173619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:02:33.535924Z","time spent":"637.633815ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:02:34.17363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:02:33.610704Z","time spent":"562.823185ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:02:34.173582Z","caller":"traceutil/trace.go:171","msg":"trace[1774302236] linearizableReadLoop","detail":"{readStateIndex:1922; appliedIndex:1918; }","duration":"699.174852ms","start":"2026-06-09T17:02:33.474384Z","end":"2026-06-09T17:02:34.173559Z","steps":["trace[1774302236] 'read index received' (duration: 587.401257ms)","trace[1774302236] 'applied index is now lower than readState.Index' (duration: 111.772615ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:02:34.173816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.441002ms","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-06-09T17:02:34.173839Z","caller":"traceutil/trace.go:171","msg":"trace[1162969418] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1852; }","duration":"699.504033ms","start":"2026-06-09T17:02:33.474328Z","end":"2026-06-09T17:02:34.173832Z","steps":["trace[1162969418] 'agreement among raft nodes before linearized reading' (duration: 699.41583ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:02:34.173858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:02:33.474301Z","time spent":"699.552595ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-09T17:02:34.173866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.209067ms","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-06-09T17:02:34.173881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.041379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-05c6171e-1462-47d6-a3aa-c5c89117c0df\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-06-09T17:02:34.173918Z","caller":"traceutil/trace.go:171","msg":"trace[476511266] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-05c6171e-1462-47d6-a3aa-c5c89117c0df; range_end:; response_count:1; response_revision:1852; }","duration":"591.125031ms","start":"2026-06-09T17:02:33.582783Z","end":"2026-06-09T17:02:34.173908Z","steps":["trace[476511266] 'agreement among raft nodes before linearized reading' (duration: 590.983456ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:02:34.17392Z","caller":"traceutil/trace.go:171","msg":"trace[1282502976] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1852; }","duration":"218.27701ms","start":"2026-06-09T17:02:33.955629Z","end":"2026-06-09T17:02:34.173906Z","steps":["trace[1282502976] 'agreement among raft nodes before linearized reading' (duration: 218.179757ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:02:34.173955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:02:33.582763Z","time spent":"591.184083ms","remote":"127.0.0.1:37962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-05c6171e-1462-47d6-a3aa-c5c89117c0df\" "} {"level":"warn","ts":"2026-06-09T17:02:34.173972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.365677ms","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-06-09T17:02:34.174008Z","caller":"traceutil/trace.go:171","msg":"trace[1719755334] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1852; }","duration":"567.45911ms","start":"2026-06-09T17:02:33.60654Z","end":"2026-06-09T17:02:34.173999Z","steps":["trace[1719755334] 'agreement among raft nodes before linearized reading' (duration: 567.319465ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:02:34.174051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:02:33.606523Z","time spent":"567.503192ms","remote":"127.0.0.1:38044","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-06-09T17:02:34.174043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.991737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-05c6171e-1462-47d6-a3aa-c5c89117c0df\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-06-09T17:02:34.174097Z","caller":"traceutil/trace.go:171","msg":"trace[1123334679] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-05c6171e-1462-47d6-a3aa-c5c89117c0df; range_end:; response_count:1; response_revision:1852; }","duration":"452.10854ms","start":"2026-06-09T17:02:33.721975Z","end":"2026-06-09T17:02:34.174083Z","steps":["trace[1123334679] 'agreement among raft nodes before linearized reading' (duration: 451.857131ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:02:34.174132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:02:33.72195Z","time spent":"452.173193ms","remote":"127.0.0.1:37962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-05c6171e-1462-47d6-a3aa-c5c89117c0df\" "} {"level":"info","ts":"2026-06-09T17:02:49.758702Z","caller":"traceutil/trace.go:171","msg":"trace[579422439] linearizableReadLoop","detail":"{readStateIndex:2054; appliedIndex:2053; }","duration":"467.744673ms","start":"2026-06-09T17:02:49.290932Z","end":"2026-06-09T17:02:49.758677Z","steps":["trace[579422439] 'read index received' (duration: 467.62594ms)","trace[579422439] 'applied index is now lower than readState.Index' (duration: 117.553µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:02:49.758771Z","caller":"traceutil/trace.go:171","msg":"trace[1190118184] transaction","detail":"{read_only:false; response_revision:1979; number_of_response:1; }","duration":"621.699902ms","start":"2026-06-09T17:02:49.137032Z","end":"2026-06-09T17:02:49.758732Z","steps":["trace[1190118184] 'process raft request' (duration: 621.468494ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:02:49.758934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:02:49.13701Z","time spent":"621.832367ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:02:49.759095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.99884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-06-09T17:02:49.759085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.426939ms","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-06-09T17:02:49.759146Z","caller":"traceutil/trace.go:171","msg":"trace[475895970] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1979; }","duration":"333.105844ms","start":"2026-06-09T17:02:49.426026Z","end":"2026-06-09T17:02:49.759132Z","steps":["trace[475895970] 'agreement among raft nodes before linearized reading' (duration: 332.929378ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:02:49.759168Z","caller":"traceutil/trace.go:171","msg":"trace[1688236545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1979; }","duration":"453.563474ms","start":"2026-06-09T17:02:49.305581Z","end":"2026-06-09T17:02:49.759145Z","steps":["trace[1688236545] 'agreement among raft nodes before linearized reading' (duration: 453.307676ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:02:49.759182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:02:49.426003Z","time spent":"333.171195ms","remote":"127.0.0.1:50662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-09T17:02:49.759184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.364734ms","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-06-09T17:02:49.759212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:02:49.305566Z","time spent":"453.637087ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-09T17:02:49.759225Z","caller":"traceutil/trace.go:171","msg":"trace[1943210056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1979; }","duration":"452.422396ms","start":"2026-06-09T17:02:49.306792Z","end":"2026-06-09T17:02:49.759215Z","steps":["trace[1943210056] 'agreement among raft nodes before linearized reading' (duration: 452.2648ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:02:49.759258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:02:49.306783Z","time spent":"452.469217ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-09T17:02:49.759474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.533419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:02:49.759522Z","caller":"traceutil/trace.go:171","msg":"trace[30567687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1979; }","duration":"468.623113ms","start":"2026-06-09T17:02:49.290886Z","end":"2026-06-09T17:02:49.759509Z","steps":["trace[30567687] 'agreement among raft nodes before linearized reading' (duration: 467.95645ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:02:49.759587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:02:49.290864Z","time spent":"468.696824ms","remote":"127.0.0.1:37802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-09T17:03:00.404071Z","caller":"traceutil/trace.go:171","msg":"trace[1219405511] linearizableReadLoop","detail":"{readStateIndex:2122; appliedIndex:2121; }","duration":"104.234467ms","start":"2026-06-09T17:03:00.299807Z","end":"2026-06-09T17:03:00.404041Z","steps":["trace[1219405511] 'read index received' (duration: 104.195536ms)","trace[1219405511] 'applied index is now lower than readState.Index' (duration: 37.201µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:00.404159Z","caller":"traceutil/trace.go:171","msg":"trace[1832711959] transaction","detail":"{read_only:false; response_revision:2045; number_of_response:1; }","duration":"106.594332ms","start":"2026-06-09T17:03:00.29752Z","end":"2026-06-09T17:03:00.404114Z","steps":["trace[1832711959] 'process raft request' (duration: 106.323953ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:00.404524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.400153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:03:00.404647Z","caller":"traceutil/trace.go:171","msg":"trace[1312144638] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2045; }","duration":"104.839967ms","start":"2026-06-09T17:03:00.299782Z","end":"2026-06-09T17:03:00.404622Z","steps":["trace[1312144638] 'agreement among raft nodes before linearized reading' (duration: 104.387232ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:00.408073Z","caller":"traceutil/trace.go:171","msg":"trace[1618342583] transaction","detail":"{read_only:false; response_revision:2046; number_of_response:1; }","duration":"107.970565ms","start":"2026-06-09T17:03:00.300076Z","end":"2026-06-09T17:03:00.408046Z","steps":["trace[1618342583] 'process raft request' (duration: 107.78706ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:10.582348Z","caller":"traceutil/trace.go:171","msg":"trace[764238881] linearizableReadLoop","detail":"{readStateIndex:2179; appliedIndex:2178; }","duration":"124.491532ms","start":"2026-06-09T17:03:10.457834Z","end":"2026-06-09T17:03:10.582326Z","steps":["trace[764238881] 'read index received' (duration: 101.101801ms)","trace[764238881] 'applied index is now lower than readState.Index' (duration: 23.388621ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:03:10.582679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.329027ms","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-06-09T17:03:10.582695Z","caller":"traceutil/trace.go:171","msg":"trace[125975823] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"139.144998ms","start":"2026-06-09T17:03:10.443299Z","end":"2026-06-09T17:03:10.582444Z","steps":["trace[125975823] 'process raft request' (duration: 115.706275ms)","trace[125975823] 'compare' (duration: 23.216556ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:10.582762Z","caller":"traceutil/trace.go:171","msg":"trace[53218663] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2100; }","duration":"120.478641ms","start":"2026-06-09T17:03:10.462272Z","end":"2026-06-09T17:03:10.58275Z","steps":["trace[53218663] 'agreement among raft nodes before linearized reading' (duration: 120.261814ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:10.582666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.806261ms","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-06-09T17:03:10.583012Z","caller":"traceutil/trace.go:171","msg":"trace[1718746112] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2100; }","duration":"125.188973ms","start":"2026-06-09T17:03:10.457797Z","end":"2026-06-09T17:03:10.582986Z","steps":["trace[1718746112] 'agreement among raft nodes before linearized reading' (duration: 124.694968ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:12.71035Z","caller":"traceutil/trace.go:171","msg":"trace[662295682] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"257.211421ms","start":"2026-06-09T17:03:12.453116Z","end":"2026-06-09T17:03:12.710328Z","steps":["trace[662295682] 'process raft request' (duration: 257.037726ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:12.882608Z","caller":"traceutil/trace.go:171","msg":"trace[1271796142] linearizableReadLoop","detail":"{readStateIndex:2192; appliedIndex:2189; }","duration":"266.379648ms","start":"2026-06-09T17:03:12.616206Z","end":"2026-06-09T17:03:12.882586Z","steps":["trace[1271796142] 'read index received' (duration: 94.435516ms)","trace[1271796142] 'applied index is now lower than readState.Index' (duration: 171.943182ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:12.882942Z","caller":"traceutil/trace.go:171","msg":"trace[568824857] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"323.640105ms","start":"2026-06-09T17:03:12.559287Z","end":"2026-06-09T17:03:12.882927Z","steps":["trace[568824857] 'process raft request' (duration: 293.177997ms)","trace[568824857] 'compare' (duration: 29.913221ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:03:12.883056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:12.559263Z","time spent":"323.719036ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:03:12.883282Z","caller":"traceutil/trace.go:171","msg":"trace[329532932] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"299.344011ms","start":"2026-06-09T17:03:12.583915Z","end":"2026-06-09T17:03:12.883259Z","steps":["trace[329532932] 'process raft request' (duration: 298.582109ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:12.883441Z","caller":"traceutil/trace.go:171","msg":"trace[731746691] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"296.478326ms","start":"2026-06-09T17:03:12.586953Z","end":"2026-06-09T17:03:12.883432Z","steps":["trace[731746691] 'process raft request' (duration: 295.594689ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:12.883686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.473571ms","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-06-09T17:03:12.883741Z","caller":"traceutil/trace.go:171","msg":"trace[330762894] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2113; }","duration":"267.545503ms","start":"2026-06-09T17:03:12.616171Z","end":"2026-06-09T17:03:12.883717Z","steps":["trace[330762894] 'agreement among raft nodes before linearized reading' (duration: 267.421409ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:12.883947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.036604ms","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-06-09T17:03:12.883981Z","caller":"traceutil/trace.go:171","msg":"trace[865001943] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2113; }","duration":"236.091206ms","start":"2026-06-09T17:03:12.647882Z","end":"2026-06-09T17:03:12.883973Z","steps":["trace[865001943] 'agreement among raft nodes before linearized reading' (duration: 235.989473ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:14.097347Z","caller":"traceutil/trace.go:171","msg":"trace[562349961] linearizableReadLoop","detail":"{readStateIndex:2195; appliedIndex:2194; }","duration":"201.039314ms","start":"2026-06-09T17:03:13.896279Z","end":"2026-06-09T17:03:14.097319Z","steps":["trace[562349961] 'read index received' (duration: 191.639974ms)","trace[562349961] 'applied index is now lower than readState.Index' (duration: 9.39788ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:03:14.097663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.359014ms","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-06-09T17:03:14.097707Z","caller":"traceutil/trace.go:171","msg":"trace[1772382602] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2115; }","duration":"201.461407ms","start":"2026-06-09T17:03:13.896233Z","end":"2026-06-09T17:03:14.097695Z","steps":["trace[1772382602] 'agreement among raft nodes before linearized reading' (duration: 201.276482ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:14.097733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.455006ms","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-06-09T17:03:14.097826Z","caller":"traceutil/trace.go:171","msg":"trace[996671399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2115; }","duration":"181.58157ms","start":"2026-06-09T17:03:13.916223Z","end":"2026-06-09T17:03:14.097804Z","steps":["trace[996671399] 'agreement among raft nodes before linearized reading' (duration: 181.318022ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:14.098032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.847308ms","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-06-09T17:03:14.098023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.58639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-09T17:03:14.098069Z","caller":"traceutil/trace.go:171","msg":"trace[1727821893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2115; }","duration":"178.923781ms","start":"2026-06-09T17:03:13.919136Z","end":"2026-06-09T17:03:14.09806Z","steps":["trace[1727821893] 'agreement among raft nodes before linearized reading' (duration: 178.818837ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:14.098114Z","caller":"traceutil/trace.go:171","msg":"trace[480783945] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2115; }","duration":"130.729492ms","start":"2026-06-09T17:03:13.967361Z","end":"2026-06-09T17:03:14.098091Z","steps":["trace[480783945] 'agreement among raft nodes before linearized reading' (duration: 130.417473ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:15.257335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.576635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:03:15.257599Z","caller":"traceutil/trace.go:171","msg":"trace[369610917] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"542.108932ms","start":"2026-06-09T17:03:14.715464Z","end":"2026-06-09T17:03:15.257573Z","steps":["trace[369610917] 'process raft request' (duration: 541.984519ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:15.257682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:14.715434Z","time spent":"542.211754ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:03:15.257878Z","caller":"traceutil/trace.go:171","msg":"trace[263283821] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"895.489621ms","start":"2026-06-09T17:03:14.362341Z","end":"2026-06-09T17:03:15.25783Z","steps":["trace[263283821] 'process raft request' (duration: 344.252165ms)","trace[263283821] 'compare' (duration: 550.006449ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:03:15.258125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:14.362316Z","time spent":"895.634425ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:03:15.452107Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1249360413915361053,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T17:03:15.701877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.531829ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:03:15.702042Z","caller":"traceutil/trace.go:171","msg":"trace[87283575] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"812.486216ms","start":"2026-06-09T17:03:14.889541Z","end":"2026-06-09T17:03:15.702027Z","steps":["trace[87283575] 'process raft request' (duration: 589.057061ms)","trace[87283575] 'compare' (duration: 222.408825ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:03:15.702125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:14.889522Z","time spent":"812.562299ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:03:15.702464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.690528ms","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-06-09T17:03:15.701988Z","caller":"traceutil/trace.go:171","msg":"trace[1901035905] linearizableReadLoop","detail":"{readStateIndex:2202; appliedIndex:2201; }","duration":"750.224364ms","start":"2026-06-09T17:03:14.951745Z","end":"2026-06-09T17:03:15.701969Z","steps":["trace[1901035905] 'read index received' (duration: 526.918933ms)","trace[1901035905] 'applied index is now lower than readState.Index' (duration: 223.303961ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:15.702557Z","caller":"traceutil/trace.go:171","msg":"trace[514648121] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2122; }","duration":"750.841172ms","start":"2026-06-09T17:03:14.951695Z","end":"2026-06-09T17:03:15.702536Z","steps":["trace[514648121] 'agreement among raft nodes before linearized reading' (duration: 750.43651ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:15.7026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:14.95167Z","time spent":"750.920595ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-09T17:03:15.702696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.382014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:03:15.70274Z","caller":"traceutil/trace.go:171","msg":"trace[35739181] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2122; }","duration":"411.490068ms","start":"2026-06-09T17:03:15.291239Z","end":"2026-06-09T17:03:15.702729Z","steps":["trace[35739181] 'agreement among raft nodes before linearized reading' (duration: 411.419016ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:15.702771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:15.291219Z","time spent":"411.544859ms","remote":"127.0.0.1:37794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T17:03:15.702997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.86894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-06-09T17:03:15.703048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.488223ms","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-06-09T17:03:15.703087Z","caller":"traceutil/trace.go:171","msg":"trace[1727226435] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2122; }","duration":"738.571625ms","start":"2026-06-09T17:03:14.964506Z","end":"2026-06-09T17:03:15.703078Z","steps":["trace[1727226435] 'agreement among raft nodes before linearized reading' (duration: 738.121502ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:15.703123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:14.964485Z","time spent":"738.631987ms","remote":"127.0.0.1:38044","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-06-09T17:03:15.703159Z","caller":"traceutil/trace.go:171","msg":"trace[1777508877] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2122; }","duration":"597.024694ms","start":"2026-06-09T17:03:15.10605Z","end":"2026-06-09T17:03:15.703075Z","steps":["trace[1777508877] 'agreement among raft nodes before linearized reading' (duration: 596.698965ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:15.704645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:15.106021Z","time spent":"598.416546ms","remote":"127.0.0.1:50662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-09T17:03:16.695763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.326257ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:03:16.695873Z","caller":"traceutil/trace.go:171","msg":"trace[1089863525] linearizableReadLoop","detail":"{readStateIndex:2204; appliedIndex:2203; }","duration":"697.593763ms","start":"2026-06-09T17:03:15.99826Z","end":"2026-06-09T17:03:16.695854Z","steps":["trace[1089863525] 'read index received' (duration: 192.080411ms)","trace[1089863525] 'applied index is now lower than readState.Index' (duration: 505.512282ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:16.695946Z","caller":"traceutil/trace.go:171","msg":"trace[869422057] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"988.339122ms","start":"2026-06-09T17:03:15.707592Z","end":"2026-06-09T17:03:16.695931Z","steps":["trace[869422057] 'process raft request' (duration: 482.762558ms)","trace[869422057] 'compare' (duration: 505.222304ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:03:16.696026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:15.70758Z","time spent":"988.403323ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:03:16.696501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.239972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b7786b9002e4c9\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-06-09T17:03:16.696547Z","caller":"traceutil/trace.go:171","msg":"trace[1431015501] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18b7786b9002e4c9; range_end:; response_count:1; response_revision:2124; }","duration":"698.320624ms","start":"2026-06-09T17:03:15.998212Z","end":"2026-06-09T17:03:16.696532Z","steps":["trace[1431015501] 'agreement among raft nodes before linearized reading' (duration: 698.17845ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:16.696579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:15.998192Z","time spent":"698.379845ms","remote":"127.0.0.1:37862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b7786b9002e4c9\" "} {"level":"warn","ts":"2026-06-09T17:03:16.705872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.985067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T17:03:16.706217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.268646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:129869"} {"level":"info","ts":"2026-06-09T17:03:16.706219Z","caller":"traceutil/trace.go:171","msg":"trace[388703952] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:2125; }","duration":"563.367458ms","start":"2026-06-09T17:03:16.142833Z","end":"2026-06-09T17:03:16.706201Z","steps":["trace[388703952] 'agreement among raft nodes before linearized reading' (duration: 562.961307ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:16.706248Z","caller":"traceutil/trace.go:171","msg":"trace[1500956895] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2125; }","duration":"159.333318ms","start":"2026-06-09T17:03:16.546907Z","end":"2026-06-09T17:03:16.706241Z","steps":["trace[1500956895] 'agreement among raft nodes before linearized reading' (duration: 159.226276ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:16.706279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:16.142815Z","time spent":"563.44748ms","remote":"127.0.0.1:45642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-06-09T17:03:16.706056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.310645ms","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-06-09T17:03:16.706508Z","caller":"traceutil/trace.go:171","msg":"trace[1644428529] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2125; }","duration":"505.7986ms","start":"2026-06-09T17:03:16.200697Z","end":"2026-06-09T17:03:16.706496Z","steps":["trace[1644428529] 'agreement among raft nodes before linearized reading' (duration: 505.255754ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:16.7065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.986547ms","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-06-09T17:03:16.706547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.047174ms","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-06-09T17:03:16.706609Z","caller":"traceutil/trace.go:171","msg":"trace[1393047005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2125; }","duration":"518.149556ms","start":"2026-06-09T17:03:16.188442Z","end":"2026-06-09T17:03:16.706592Z","steps":["trace[1393047005] 'agreement among raft nodes before linearized reading' (duration: 517.987912ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:16.706624Z","caller":"traceutil/trace.go:171","msg":"trace[1777011008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2125; }","duration":"507.116289ms","start":"2026-06-09T17:03:16.199468Z","end":"2026-06-09T17:03:16.706584Z","steps":["trace[1777011008] 'agreement among raft nodes before linearized reading' (duration: 506.752379ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:16.706656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:16.188421Z","time spent":"518.22669ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-09T17:03:16.706665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:16.199457Z","time spent":"507.197452ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-09T17:03:16.706551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:16.200686Z","time spent":"505.854612ms","remote":"127.0.0.1:38044","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-06-09T17:03:16.706791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.85234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12383"} {"level":"info","ts":"2026-06-09T17:03:16.706833Z","caller":"traceutil/trace.go:171","msg":"trace[826754536] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2125; }","duration":"544.939742ms","start":"2026-06-09T17:03:16.161883Z","end":"2026-06-09T17:03:16.706823Z","steps":["trace[826754536] 'agreement among raft nodes before linearized reading' (duration: 544.819158ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:16.706861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:16.161863Z","time spent":"544.991934ms","remote":"127.0.0.1:37962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12406,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-09T17:03:16.706476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.592496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-06-09T17:03:16.706917Z","caller":"traceutil/trace.go:171","msg":"trace[1777410845] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2125; }","duration":"160.07038ms","start":"2026-06-09T17:03:16.546835Z","end":"2026-06-09T17:03:16.706906Z","steps":["trace[1777410845] 'agreement among raft nodes before linearized reading' (duration: 159.615227ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:16.706113Z","caller":"traceutil/trace.go:171","msg":"trace[738199470] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"376.763791ms","start":"2026-06-09T17:03:16.329333Z","end":"2026-06-09T17:03:16.706097Z","steps":["trace[738199470] 'process raft request' (duration: 376.351929ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:16.707093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:03:16.329315Z","time spent":"377.727819ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:03:23.468839Z","caller":"traceutil/trace.go:171","msg":"trace[1740514549] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"142.98199ms","start":"2026-06-09T17:03:23.325824Z","end":"2026-06-09T17:03:23.468805Z","steps":["trace[1740514549] 'process raft request' (duration: 107.782003ms)","trace[1740514549] 'compare' (duration: 34.992571ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:49.754522Z","caller":"traceutil/trace.go:171","msg":"trace[2101916596] transaction","detail":"{read_only:false; response_revision:2322; number_of_response:1; }","duration":"195.613161ms","start":"2026-06-09T17:03:49.558875Z","end":"2026-06-09T17:03:49.754489Z","steps":["trace[2101916596] 'process raft request' (duration: 112.927353ms)","trace[2101916596] 'compare' (duration: 82.41277ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:03:50.084458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.301393ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:03:50.084616Z","caller":"traceutil/trace.go:171","msg":"trace[1473824185] linearizableReadLoop","detail":"{readStateIndex:2411; appliedIndex:2410; }","duration":"139.140857ms","start":"2026-06-09T17:03:49.945431Z","end":"2026-06-09T17:03:50.084572Z","steps":["trace[1473824185] 'read index received' (duration: 44.381µs)","trace[1473824185] 'applied index is now lower than readState.Index' (duration: 139.094386ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:50.084775Z","caller":"traceutil/trace.go:171","msg":"trace[1045760299] transaction","detail":"{read_only:false; response_revision:2323; number_of_response:1; }","duration":"255.777638ms","start":"2026-06-09T17:03:49.828927Z","end":"2026-06-09T17:03:50.084705Z","steps":["trace[1045760299] 'process raft request' (duration: 35.113356ms)","trace[1045760299] 'compare' (duration: 220.062807ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:03:50.084919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.479976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-06-09T17:03:50.084978Z","caller":"traceutil/trace.go:171","msg":"trace[1871976214] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2323; }","duration":"139.582228ms","start":"2026-06-09T17:03:49.945381Z","end":"2026-06-09T17:03:50.084963Z","steps":["trace[1871976214] 'agreement among raft nodes before linearized reading' (duration: 139.365543ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:50.085043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.238043ms","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-06-09T17:03:50.085132Z","caller":"traceutil/trace.go:171","msg":"trace[817824100] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2323; }","duration":"127.408967ms","start":"2026-06-09T17:03:49.957706Z","end":"2026-06-09T17:03:50.085115Z","steps":["trace[817824100] 'agreement among raft nodes before linearized reading' (duration: 127.12043ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:50.270893Z","caller":"traceutil/trace.go:171","msg":"trace[1762035556] linearizableReadLoop","detail":"{readStateIndex:2412; appliedIndex:2411; }","duration":"179.296716ms","start":"2026-06-09T17:03:50.091568Z","end":"2026-06-09T17:03:50.270864Z","steps":["trace[1762035556] 'read index received' (duration: 104.987196ms)","trace[1762035556] 'applied index is now lower than readState.Index' (duration: 74.30796ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:50.270962Z","caller":"traceutil/trace.go:171","msg":"trace[841548725] transaction","detail":"{read_only:false; response_revision:2324; number_of_response:1; }","duration":"180.113448ms","start":"2026-06-09T17:03:50.090821Z","end":"2026-06-09T17:03:50.270934Z","steps":["trace[841548725] 'process raft request' (duration: 105.838528ms)","trace[841548725] 'compare' (duration: 73.971732ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:03:50.271368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.772258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:8553"} {"level":"info","ts":"2026-06-09T17:03:50.271431Z","caller":"traceutil/trace.go:171","msg":"trace[389894892] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:2324; }","duration":"179.877731ms","start":"2026-06-09T17:03:50.091537Z","end":"2026-06-09T17:03:50.271415Z","steps":["trace[389894892] 'agreement among raft nodes before linearized reading' (duration: 179.4425ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:50.968456Z","caller":"traceutil/trace.go:171","msg":"trace[1585342668] transaction","detail":"{read_only:false; response_revision:2327; number_of_response:1; }","duration":"112.924224ms","start":"2026-06-09T17:03:50.855486Z","end":"2026-06-09T17:03:50.96841Z","steps":["trace[1585342668] 'process raft request' (duration: 112.556714ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:50.969122Z","caller":"traceutil/trace.go:171","msg":"trace[76811284] linearizableReadLoop","detail":"{readStateIndex:2415; appliedIndex:2415; }","duration":"103.652782ms","start":"2026-06-09T17:03:50.865454Z","end":"2026-06-09T17:03:50.969107Z","steps":["trace[76811284] 'read index received' (duration: 103.646071ms)","trace[76811284] 'applied index is now lower than readState.Index' (duration: 5.311µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:03:50.969415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.934589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-06-09T17:03:50.969468Z","caller":"traceutil/trace.go:171","msg":"trace[639118504] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2327; }","duration":"104.033172ms","start":"2026-06-09T17:03:50.865418Z","end":"2026-06-09T17:03:50.969451Z","steps":["trace[639118504] 'agreement among raft nodes before linearized reading' (duration: 103.803195ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:50.992425Z","caller":"traceutil/trace.go:171","msg":"trace[683692530] transaction","detail":"{read_only:false; response_revision:2328; number_of_response:1; }","duration":"122.65865ms","start":"2026-06-09T17:03:50.86974Z","end":"2026-06-09T17:03:50.992399Z","steps":["trace[683692530] 'process raft request' (duration: 122.233819ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:51.172082Z","caller":"traceutil/trace.go:171","msg":"trace[1574657486] transaction","detail":"{read_only:false; response_revision:2330; number_of_response:1; }","duration":"176.441646ms","start":"2026-06-09T17:03:50.995597Z","end":"2026-06-09T17:03:51.172038Z","steps":["trace[1574657486] 'process raft request' (duration: 165.524271ms)","trace[1574657486] 'compare' (duration: 10.277909ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:51.172172Z","caller":"traceutil/trace.go:171","msg":"trace[1836371434] linearizableReadLoop","detail":"{readStateIndex:2418; appliedIndex:2416; }","duration":"163.459516ms","start":"2026-06-09T17:03:51.008668Z","end":"2026-06-09T17:03:51.172128Z","steps":["trace[1836371434] 'read index received' (duration: 12.442975ms)","trace[1836371434] 'applied index is now lower than readState.Index' (duration: 151.011801ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:03:51.172197Z","caller":"traceutil/trace.go:171","msg":"trace[1367907001] transaction","detail":"{read_only:false; response_revision:2332; number_of_response:1; }","duration":"144.566153ms","start":"2026-06-09T17:03:51.027606Z","end":"2026-06-09T17:03:51.172172Z","steps":["trace[1367907001] 'process raft request' (duration: 144.496321ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:51.172419Z","caller":"traceutil/trace.go:171","msg":"trace[189366259] transaction","detail":"{read_only:false; response_revision:2331; number_of_response:1; }","duration":"145.769244ms","start":"2026-06-09T17:03:51.026431Z","end":"2026-06-09T17:03:51.172201Z","steps":["trace[189366259] 'process raft request' (duration: 145.539607ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:51.172578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.877997ms","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-06-09T17:03:51.173105Z","caller":"traceutil/trace.go:171","msg":"trace[535132054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2332; }","duration":"164.436811ms","start":"2026-06-09T17:03:51.008626Z","end":"2026-06-09T17:03:51.173062Z","steps":["trace[535132054] 'agreement among raft nodes before linearized reading' (duration: 163.703012ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:51.17256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.635705ms","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-06-09T17:03:51.17375Z","caller":"traceutil/trace.go:171","msg":"trace[127699639] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2332; }","duration":"116.856988ms","start":"2026-06-09T17:03:51.056869Z","end":"2026-06-09T17:03:51.173726Z","steps":["trace[127699639] 'agreement among raft nodes before linearized reading' (duration: 115.495652ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:03:51.172593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.60978ms","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-06-09T17:03:51.174089Z","caller":"traceutil/trace.go:171","msg":"trace[1832476684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2332; }","duration":"139.159002ms","start":"2026-06-09T17:03:51.034905Z","end":"2026-06-09T17:03:51.174064Z","steps":["trace[1832476684] 'agreement among raft nodes before linearized reading' (duration: 137.455726ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:03:55.597669Z","caller":"traceutil/trace.go:171","msg":"trace[402896441] transaction","detail":"{read_only:false; response_revision:2389; number_of_response:1; }","duration":"131.192456ms","start":"2026-06-09T17:03:55.466446Z","end":"2026-06-09T17:03:55.597639Z","steps":["trace[402896441] 'process raft request' (duration: 130.967851ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:05.338887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.97198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-06-09T17:04:05.338962Z","caller":"traceutil/trace.go:171","msg":"trace[984353164] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2491; }","duration":"765.091453ms","start":"2026-06-09T17:04:04.573853Z","end":"2026-06-09T17:04:05.338944Z","steps":["trace[984353164] 'range keys from in-memory index tree' (duration: 764.826407ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:05.339001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:04.573833Z","time spent":"765.159585ms","remote":"127.0.0.1:37970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-06-09T17:04:05.339591Z","caller":"traceutil/trace.go:171","msg":"trace[936056118] linearizableReadLoop","detail":"{readStateIndex:2584; appliedIndex:2583; }","duration":"183.17371ms","start":"2026-06-09T17:04:05.156397Z","end":"2026-06-09T17:04:05.33957Z","steps":["trace[936056118] 'read index received' (duration: 180.891813ms)","trace[936056118] 'applied index is now lower than readState.Index' (duration: 2.280176ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:05.339731Z","caller":"traceutil/trace.go:171","msg":"trace[1378763592] transaction","detail":"{read_only:false; response_revision:2492; number_of_response:1; }","duration":"263.902439ms","start":"2026-06-09T17:04:05.075812Z","end":"2026-06-09T17:04:05.339715Z","steps":["trace[1378763592] 'process raft request' (duration: 261.504129ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:05.340274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.836286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-06-09T17:04:05.340384Z","caller":"traceutil/trace.go:171","msg":"trace[198041171] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2492; }","duration":"183.969259ms","start":"2026-06-09T17:04:05.15639Z","end":"2026-06-09T17:04:05.34036Z","steps":["trace[198041171] 'agreement among raft nodes before linearized reading' (duration: 183.626271ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:04:42.687328Z","caller":"traceutil/trace.go:171","msg":"trace[792656375] transaction","detail":"{read_only:false; response_revision:2698; number_of_response:1; }","duration":"295.754593ms","start":"2026-06-09T17:04:42.391527Z","end":"2026-06-09T17:04:42.687281Z","steps":["trace[792656375] 'process raft request' (duration: 295.513967ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:42.989914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.427228ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:04:42.990059Z","caller":"traceutil/trace.go:171","msg":"trace[1027691298] linearizableReadLoop","detail":"{readStateIndex:2799; appliedIndex:2797; }","duration":"427.454896ms","start":"2026-06-09T17:04:42.562584Z","end":"2026-06-09T17:04:42.990039Z","steps":["trace[1027691298] 'read index received' (duration: 124.44976ms)","trace[1027691298] 'applied index is now lower than readState.Index' (duration: 303.003796ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:04:42.990153Z","caller":"traceutil/trace.go:171","msg":"trace[1765892755] transaction","detail":"{read_only:false; response_revision:2699; number_of_response:1; }","duration":"567.953486ms","start":"2026-06-09T17:04:42.422187Z","end":"2026-06-09T17:04:42.990141Z","steps":["trace[1765892755] 'process raft request' (duration: 411.21225ms)","trace[1765892755] 'compare' (duration: 156.289676ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:04:42.990222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:42.422146Z","time spent":"568.040568ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:04:42.990349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.955227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-06-09T17:04:42.990464Z","caller":"traceutil/trace.go:171","msg":"trace[2066121648] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2699; }","duration":"416.070089ms","start":"2026-06-09T17:04:42.574342Z","end":"2026-06-09T17:04:42.990412Z","steps":["trace[2066121648] 'agreement among raft nodes before linearized reading' (duration: 415.809353ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:42.99051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:42.574325Z","time spent":"416.174152ms","remote":"127.0.0.1:37970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-06-09T17:04:42.990642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.119115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T17:04:42.990691Z","caller":"traceutil/trace.go:171","msg":"trace[2103314590] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2699; }","duration":"153.222677ms","start":"2026-06-09T17:04:42.837456Z","end":"2026-06-09T17:04:42.990679Z","steps":["trace[2103314590] 'agreement among raft nodes before linearized reading' (duration: 153.141545ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:42.990867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.278694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-09T17:04:42.990921Z","caller":"traceutil/trace.go:171","msg":"trace[733742248] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2699; }","duration":"428.377186ms","start":"2026-06-09T17:04:42.562523Z","end":"2026-06-09T17:04:42.9909Z","steps":["trace[733742248] 'agreement among raft nodes before linearized reading' (duration: 428.233233ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:04:42.991309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:04:42.562496Z","time spent":"428.792617ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-09T17:04:42.990974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.023313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18b7787d74e5566a\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-06-09T17:04:42.991474Z","caller":"traceutil/trace.go:171","msg":"trace[1649166312] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18b7787d74e5566a; range_end:; response_count:1; response_revision:2699; }","duration":"113.799ms","start":"2026-06-09T17:04:42.877609Z","end":"2026-06-09T17:04:42.991408Z","steps":["trace[1649166312] 'agreement among raft nodes before linearized reading' (duration: 112.944881ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:07:08.584097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.477563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-06-09T17:07:08.584101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.666807ms","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-06-09T17:07:08.584155Z","caller":"traceutil/trace.go:171","msg":"trace[1747571711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3555; }","duration":"114.559714ms","start":"2026-06-09T17:07:08.469583Z","end":"2026-06-09T17:07:08.584143Z","steps":["trace[1747571711] 'range keys from in-memory index tree' (duration: 114.347161ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:07:08.584186Z","caller":"traceutil/trace.go:171","msg":"trace[1460096639] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3555; }","duration":"137.80218ms","start":"2026-06-09T17:07:08.446365Z","end":"2026-06-09T17:07:08.584167Z","steps":["trace[1460096639] 'range keys from in-memory index tree' (duration: 137.402942ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:07:08.584086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.480016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:07:08.58432Z","caller":"traceutil/trace.go:171","msg":"trace[524737331] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:3555; }","duration":"156.756788ms","start":"2026-06-09T17:07:08.427539Z","end":"2026-06-09T17:07:08.584295Z","steps":["trace[524737331] 'count revisions from in-memory index tree' (duration: 156.395664ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:20.912982Z","caller":"traceutil/trace.go:171","msg":"trace[2118967996] transaction","detail":"{read_only:false; response_revision:4035; number_of_response:1; }","duration":"199.841865ms","start":"2026-06-09T17:08:20.713116Z","end":"2026-06-09T17:08:20.912958Z","steps":["trace[2118967996] 'process raft request' (duration: 199.639833ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:20.91384Z","caller":"traceutil/trace.go:171","msg":"trace[1992929524] transaction","detail":"{read_only:false; response_revision:4036; number_of_response:1; }","duration":"136.745182ms","start":"2026-06-09T17:08:20.777072Z","end":"2026-06-09T17:08:20.913817Z","steps":["trace[1992929524] 'process raft request' (duration: 136.551748ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:28.557634Z","caller":"traceutil/trace.go:171","msg":"trace[2090402431] transaction","detail":"{read_only:false; response_revision:4476; number_of_response:1; }","duration":"138.060931ms","start":"2026-06-09T17:08:28.419509Z","end":"2026-06-09T17:08:28.55757Z","steps":["trace[2090402431] 'process raft request' (duration: 95.572788ms)","trace[2090402431] 'compare' (duration: 41.986224ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:08:30.835478Z","caller":"traceutil/trace.go:171","msg":"trace[1431018912] transaction","detail":"{read_only:false; response_revision:4520; number_of_response:1; }","duration":"110.621492ms","start":"2026-06-09T17:08:30.724818Z","end":"2026-06-09T17:08:30.835439Z","steps":["trace[1431018912] 'process raft request' (duration: 109.980561ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:34.910259Z","caller":"traceutil/trace.go:171","msg":"trace[800760746] transaction","detail":"{read_only:false; response_revision:4605; number_of_response:1; }","duration":"134.316563ms","start":"2026-06-09T17:08:34.77589Z","end":"2026-06-09T17:08:34.910206Z","steps":["trace[800760746] 'process raft request' (duration: 134.030849ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:35.666304Z","caller":"traceutil/trace.go:171","msg":"trace[2110364404] transaction","detail":"{read_only:false; response_revision:4608; number_of_response:1; }","duration":"631.913521ms","start":"2026-06-09T17:08:35.034347Z","end":"2026-06-09T17:08:35.666261Z","steps":["trace[2110364404] 'process raft request' (duration: 557.979232ms)","trace[2110364404] 'compare' (duration: 73.788077ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:08:35.666778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:35.034312Z","time spent":"632.096844ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:08:35.706534Z","caller":"traceutil/trace.go:171","msg":"trace[1563813914] transaction","detail":"{read_only:false; response_revision:4610; number_of_response:1; }","duration":"671.123449ms","start":"2026-06-09T17:08:35.035367Z","end":"2026-06-09T17:08:35.70649Z","steps":["trace[1563813914] 'process raft request' (duration: 671.043678ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:35.706606Z","caller":"traceutil/trace.go:171","msg":"trace[1148079230] transaction","detail":"{read_only:false; response_revision:4609; number_of_response:1; }","duration":"671.974402ms","start":"2026-06-09T17:08:35.034565Z","end":"2026-06-09T17:08:35.706539Z","steps":["trace[1148079230] 'process raft request' (duration: 671.699477ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:35.706875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:35.034558Z","time spent":"672.174886ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:08:35.706892Z","caller":"traceutil/trace.go:171","msg":"trace[1538633680] linearizableReadLoop","detail":"{readStateIndex:4790; appliedIndex:4783; }","duration":"592.174748ms","start":"2026-06-09T17:08:35.11469Z","end":"2026-06-09T17:08:35.706865Z","steps":["trace[1538633680] 'read index received' (duration: 477.655987ms)","trace[1538633680] 'applied index is now lower than readState.Index' (duration: 114.517411ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:08:35.707199Z","caller":"traceutil/trace.go:171","msg":"trace[1839101142] transaction","detail":"{read_only:false; response_revision:4613; number_of_response:1; }","duration":"669.539243ms","start":"2026-06-09T17:08:35.037646Z","end":"2026-06-09T17:08:35.707185Z","steps":["trace[1839101142] 'process raft request' (duration: 669.080366ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:35.707235Z","caller":"traceutil/trace.go:171","msg":"trace[206718345] transaction","detail":"{read_only:false; response_revision:4614; number_of_response:1; }","duration":"600.124976ms","start":"2026-06-09T17:08:35.107084Z","end":"2026-06-09T17:08:35.707209Z","steps":["trace[206718345] 'process raft request' (duration: 599.730619ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:35.707235Z","caller":"traceutil/trace.go:171","msg":"trace[1716926077] transaction","detail":"{read_only:false; response_revision:4612; number_of_response:1; }","duration":"671.373722ms","start":"2026-06-09T17:08:35.035813Z","end":"2026-06-09T17:08:35.707187Z","steps":["trace[1716926077] 'process raft request' (duration: 670.848194ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:35.707317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:35.107065Z","time spent":"600.216308ms","remote":"127.0.0.1:45632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2797,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T17:08:35.70734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:35.037638Z","time spent":"669.593844ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:08:35.707422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:35.035806Z","time spent":"671.505044ms","remote":"127.0.0.1:38058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1854,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:08:35.707499Z","caller":"traceutil/trace.go:171","msg":"trace[1008568321] transaction","detail":"{read_only:false; response_revision:4611; number_of_response:1; }","duration":"671.85192ms","start":"2026-06-09T17:08:35.035637Z","end":"2026-06-09T17:08:35.707489Z","steps":["trace[1008568321] 'process raft request' (duration: 670.834074ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:35.70755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:35.035628Z","time spent":"671.894021ms","remote":"127.0.0.1:38304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4883,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:08:35.707597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.891848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-09T17:08:35.707654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:35.035359Z","time spent":"671.246081ms","remote":"127.0.0.1:37946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1250,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:08:35.711839Z","caller":"traceutil/trace.go:171","msg":"trace[905981624] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4614; }","duration":"593.01674ms","start":"2026-06-09T17:08:35.11463Z","end":"2026-06-09T17:08:35.707647Z","steps":["trace[905981624] 'agreement among raft nodes before linearized reading' (duration: 592.770636ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:35.712043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:35.114602Z","time spent":"597.34007ms","remote":"127.0.0.1:38044","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-06-09T17:08:35.721092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.265361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:08:35.721158Z","caller":"traceutil/trace.go:171","msg":"trace[747246458] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4615; }","duration":"429.338192ms","start":"2026-06-09T17:08:35.291808Z","end":"2026-06-09T17:08:35.721146Z","steps":["trace[747246458] 'agreement among raft nodes before linearized reading' (duration: 429.21455ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:35.721301Z","caller":"traceutil/trace.go:171","msg":"trace[940141113] transaction","detail":"{read_only:false; response_revision:4615; number_of_response:1; }","duration":"103.883517ms","start":"2026-06-09T17:08:35.617366Z","end":"2026-06-09T17:08:35.721249Z","steps":["trace[940141113] 'process raft request' (duration: 103.584902ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:35.721373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.682669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-b26bd\" ","response":"range_response_count:1 size:9757"} {"level":"info","ts":"2026-06-09T17:08:35.721898Z","caller":"traceutil/trace.go:171","msg":"trace[479349852] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-b26bd; range_end:; response_count:1; response_revision:4615; }","duration":"286.24997ms","start":"2026-06-09T17:08:35.435637Z","end":"2026-06-09T17:08:35.721887Z","steps":["trace[479349852] 'agreement among raft nodes before linearized reading' (duration: 285.6529ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:35.721933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.13959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-06-09T17:08:35.721936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:35.29173Z","time spent":"430.184106ms","remote":"127.0.0.1:37794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-09T17:08:35.721984Z","caller":"traceutil/trace.go:171","msg":"trace[1406580566] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4615; }","duration":"402.288064ms","start":"2026-06-09T17:08:35.319684Z","end":"2026-06-09T17:08:35.721973Z","steps":["trace[1406580566] 'agreement among raft nodes before linearized reading' (duration: 402.05161ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:35.722023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:35.319649Z","time spent":"402.364465ms","remote":"127.0.0.1:37962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-06-09T17:08:36.630717Z","caller":"traceutil/trace.go:171","msg":"trace[1729507566] transaction","detail":"{read_only:false; response_revision:4624; number_of_response:1; }","duration":"181.551276ms","start":"2026-06-09T17:08:36.449132Z","end":"2026-06-09T17:08:36.630683Z","steps":["trace[1729507566] 'process raft request' (duration: 96.894732ms)","trace[1729507566] 'compare' (duration: 84.361389ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:08:36.638798Z","caller":"traceutil/trace.go:171","msg":"trace[1018858250] transaction","detail":"{read_only:false; response_revision:4625; number_of_response:1; }","duration":"181.950114ms","start":"2026-06-09T17:08:36.456817Z","end":"2026-06-09T17:08:36.638767Z","steps":["trace[1018858250] 'process raft request' (duration: 181.796971ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:42.243824Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1249360413915367396,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-09T17:08:42.393421Z","caller":"traceutil/trace.go:171","msg":"trace[1013616680] linearizableReadLoop","detail":"{readStateIndex:4898; appliedIndex:4897; }","duration":"649.963466ms","start":"2026-06-09T17:08:41.743416Z","end":"2026-06-09T17:08:42.39338Z","steps":["trace[1013616680] 'read index received' (duration: 641.211094ms)","trace[1013616680] 'applied index is now lower than readState.Index' (duration: 8.750732ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:08:42.393458Z","caller":"traceutil/trace.go:171","msg":"trace[207026171] transaction","detail":"{read_only:false; response_revision:4720; number_of_response:1; }","duration":"654.477499ms","start":"2026-06-09T17:08:41.738927Z","end":"2026-06-09T17:08:42.393404Z","steps":["trace[207026171] 'process raft request' (duration: 645.701377ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:42.393496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.701016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-06-09T17:08:42.39381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:41.738912Z","time spent":"654.665752ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:08:42.393618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.524484ms","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-06-09T17:08:42.393982Z","caller":"traceutil/trace.go:171","msg":"trace[322894340] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4719; }","duration":"658.940281ms","start":"2026-06-09T17:08:41.735012Z","end":"2026-06-09T17:08:42.393952Z","steps":["trace[322894340] 'range keys from in-memory index tree' (duration: 656.650144ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:42.394048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:41.73499Z","time spent":"659.043142ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-09T17:08:42.393909Z","caller":"traceutil/trace.go:171","msg":"trace[1336292107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4719; }","duration":"659.169675ms","start":"2026-06-09T17:08:41.734714Z","end":"2026-06-09T17:08:42.393883Z","steps":["trace[1336292107] 'range keys from in-memory index tree' (duration: 656.926248ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:42.394421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:41.734691Z","time spent":"659.709044ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-09T17:08:42.394224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.086007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-09T17:08:42.395054Z","caller":"traceutil/trace.go:171","msg":"trace[1439094583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4719; }","duration":"705.921641ms","start":"2026-06-09T17:08:41.689096Z","end":"2026-06-09T17:08:42.395018Z","steps":["trace[1439094583] 'range keys from in-memory index tree' (duration: 702.852302ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:42.395156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:41.689075Z","time spent":"706.044023ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-09T17:08:42.394488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.752328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2520e47f-9226-406a-8fd7-fa47306d48d1\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-06-09T17:08:42.395407Z","caller":"traceutil/trace.go:171","msg":"trace[483020167] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2520e47f-9226-406a-8fd7-fa47306d48d1; range_end:; response_count:1; response_revision:4720; }","duration":"583.798584ms","start":"2026-06-09T17:08:41.811583Z","end":"2026-06-09T17:08:42.395381Z","steps":["trace[483020167] 'agreement among raft nodes before linearized reading' (duration: 582.30122ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:42.39593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:41.811558Z","time spent":"583.900177ms","remote":"127.0.0.1:37962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2520e47f-9226-406a-8fd7-fa47306d48d1\" "} {"level":"warn","ts":"2026-06-09T17:08:42.393757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.292851ms","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-06-09T17:08:42.396164Z","caller":"traceutil/trace.go:171","msg":"trace[384785228] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4720; }","duration":"652.69802ms","start":"2026-06-09T17:08:41.743398Z","end":"2026-06-09T17:08:42.396096Z","steps":["trace[384785228] 'agreement among raft nodes before linearized reading' (duration: 650.169119ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:42.396378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:41.74339Z","time spent":"652.968084ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-09T17:08:42.394271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.036492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:08:42.39663Z","caller":"traceutil/trace.go:171","msg":"trace[319858191] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:4720; }","duration":"368.46035ms","start":"2026-06-09T17:08:42.028155Z","end":"2026-06-09T17:08:42.396615Z","steps":["trace[319858191] 'agreement among raft nodes before linearized reading' (duration: 366.071832ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:42.396721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:42.028123Z","time spent":"368.579782ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-06-09T17:08:42.59258Z","caller":"traceutil/trace.go:171","msg":"trace[1449318405] transaction","detail":"{read_only:false; response_revision:4721; number_of_response:1; }","duration":"193.797335ms","start":"2026-06-09T17:08:42.398735Z","end":"2026-06-09T17:08:42.592533Z","steps":["trace[1449318405] 'process raft request' (duration: 145.417743ms)","trace[1449318405] 'compare' (duration: 48.179619ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:08:42.602121Z","caller":"traceutil/trace.go:171","msg":"trace[362871838] transaction","detail":"{read_only:false; response_revision:4722; number_of_response:1; }","duration":"201.818444ms","start":"2026-06-09T17:08:42.40029Z","end":"2026-06-09T17:08:42.602109Z","steps":["trace[362871838] 'process raft request' (duration: 201.416147ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:42.602233Z","caller":"traceutil/trace.go:171","msg":"trace[1505322396] transaction","detail":"{read_only:false; response_revision:4723; number_of_response:1; }","duration":"201.653962ms","start":"2026-06-09T17:08:42.400573Z","end":"2026-06-09T17:08:42.602227Z","steps":["trace[1505322396] 'process raft request' (duration: 201.196135ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:42.602312Z","caller":"traceutil/trace.go:171","msg":"trace[684327450] transaction","detail":"{read_only:false; response_revision:4724; number_of_response:1; }","duration":"201.343147ms","start":"2026-06-09T17:08:42.400964Z","end":"2026-06-09T17:08:42.602307Z","steps":["trace[684327450] 'process raft request' (duration: 200.826289ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:42.602387Z","caller":"traceutil/trace.go:171","msg":"trace[314962552] transaction","detail":"{read_only:false; response_revision:4725; number_of_response:1; }","duration":"200.401312ms","start":"2026-06-09T17:08:42.40198Z","end":"2026-06-09T17:08:42.602381Z","steps":["trace[314962552] 'process raft request' (duration: 199.837404ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:42.602487Z","caller":"traceutil/trace.go:171","msg":"trace[1262623934] transaction","detail":"{read_only:false; response_revision:4726; number_of_response:1; }","duration":"144.230083ms","start":"2026-06-09T17:08:42.458251Z","end":"2026-06-09T17:08:42.602481Z","steps":["trace[1262623934] 'process raft request' (duration: 143.594513ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:42.602561Z","caller":"traceutil/trace.go:171","msg":"trace[464254675] transaction","detail":"{read_only:false; response_revision:4727; number_of_response:1; }","duration":"109.47697ms","start":"2026-06-09T17:08:42.493077Z","end":"2026-06-09T17:08:42.602554Z","steps":["trace[464254675] 'process raft request' (duration: 108.792809ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:43.413855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.960521ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:08:43.414184Z","caller":"traceutil/trace.go:171","msg":"trace[178359541] linearizableReadLoop","detail":"{readStateIndex:4914; appliedIndex:4913; }","duration":"628.096886ms","start":"2026-06-09T17:08:42.786056Z","end":"2026-06-09T17:08:43.414153Z","steps":["trace[178359541] 'read index received' (duration: 155.596526ms)","trace[178359541] 'applied index is now lower than readState.Index' (duration: 472.49854ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:08:43.414297Z","caller":"traceutil/trace.go:171","msg":"trace[416480965] transaction","detail":"{read_only:false; response_revision:4738; number_of_response:1; }","duration":"348.27142ms","start":"2026-06-09T17:08:43.065971Z","end":"2026-06-09T17:08:43.414243Z","steps":["trace[416480965] 'process raft request' (duration: 348.216999ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:43.41442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:43.065949Z","time spent":"348.429613ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:08:43.414442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.373502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-06-09T17:08:43.414482Z","caller":"traceutil/trace.go:171","msg":"trace[797167252] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4738; }","duration":"628.479333ms","start":"2026-06-09T17:08:42.78599Z","end":"2026-06-09T17:08:43.414469Z","steps":["trace[797167252] 'agreement among raft nodes before linearized reading' (duration: 628.28924ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:43.414534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:42.785964Z","time spent":"628.560764ms","remote":"127.0.0.1:32972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3339,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-06-09T17:08:43.4146Z","caller":"traceutil/trace.go:171","msg":"trace[852038784] transaction","detail":"{read_only:false; response_revision:4737; number_of_response:1; }","duration":"444.543656ms","start":"2026-06-09T17:08:42.970009Z","end":"2026-06-09T17:08:43.414553Z","steps":["trace[852038784] 'process raft request' (duration: 444.034018ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:43.414675Z","caller":"traceutil/trace.go:171","msg":"trace[909372428] transaction","detail":"{read_only:false; response_revision:4736; number_of_response:1; }","duration":"640.73602ms","start":"2026-06-09T17:08:42.773284Z","end":"2026-06-09T17:08:43.41402Z","steps":["trace[909372428] 'process raft request' (duration: 168.446084ms)","trace[909372428] 'compare' (duration: 471.418732ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:08:43.414795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:42.969986Z","time spent":"444.699199ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:08:43.414868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:42.773143Z","time spent":"641.668286ms","remote":"127.0.0.1:37962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13280,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:08:43.515175Z","caller":"traceutil/trace.go:171","msg":"trace[1850673387] linearizableReadLoop","detail":"{readStateIndex:4917; appliedIndex:4916; }","duration":"100.886941ms","start":"2026-06-09T17:08:43.414255Z","end":"2026-06-09T17:08:43.515142Z","steps":["trace[1850673387] 'read index received' (duration: 28.136494ms)","trace[1850673387] 'applied index is now lower than readState.Index' (duration: 72.749087ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:08:43.737474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.617371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:08:43.737562Z","caller":"traceutil/trace.go:171","msg":"trace[1083783016] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:4739; }","duration":"566.758403ms","start":"2026-06-09T17:08:43.170779Z","end":"2026-06-09T17:08:43.737538Z","steps":["trace[1083783016] 'agreement among raft nodes before linearized reading' (duration: 344.705773ms)","trace[1083783016] 'count revisions from in-memory index tree' (duration: 221.914208ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:08:43.737606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:43.170764Z","time spent":"566.830544ms","remote":"127.0.0.1:33070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"warn","ts":"2026-06-09T17:08:43.737723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.193851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-09T17:08:43.737786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.054106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2520e47f-9226-406a-8fd7-fa47306d48d1\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-06-09T17:08:43.737819Z","caller":"traceutil/trace.go:171","msg":"trace[366601209] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:4739; }","duration":"713.348473ms","start":"2026-06-09T17:08:43.024454Z","end":"2026-06-09T17:08:43.737802Z","steps":["trace[366601209] 'agreement among raft nodes before linearized reading' (duration: 491.053029ms)","trace[366601209] 'count revisions from in-memory index tree' (duration: 222.175502ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:08:43.737869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:43.024416Z","time spent":"713.442205ms","remote":"127.0.0.1:38178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":72,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-06-09T17:08:43.737871Z","caller":"traceutil/trace.go:171","msg":"trace[648201877] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2520e47f-9226-406a-8fd7-fa47306d48d1; range_end:; response_count:1; response_revision:4739; }","duration":"335.19593ms","start":"2026-06-09T17:08:43.402656Z","end":"2026-06-09T17:08:43.737852Z","steps":["trace[648201877] 'agreement among raft nodes before linearized reading' (duration: 112.741353ms)","trace[648201877] 'range keys from in-memory index tree' (duration: 222.218972ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:08:43.737913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:43.402632Z","time spent":"335.26936ms","remote":"127.0.0.1:37962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3789,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2520e47f-9226-406a-8fd7-fa47306d48d1\" "} {"level":"warn","ts":"2026-06-09T17:08:43.739601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.671166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:08:43.739757Z","caller":"traceutil/trace.go:171","msg":"trace[1236649892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4739; }","duration":"447.878882ms","start":"2026-06-09T17:08:43.291832Z","end":"2026-06-09T17:08:43.739711Z","steps":["trace[1236649892] 'agreement among raft nodes before linearized reading' (duration: 223.593065ms)","trace[1236649892] 'range keys from in-memory index tree' (duration: 222.04183ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:08:43.739832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:43.291807Z","time spent":"448.004163ms","remote":"127.0.0.1:37794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T17:08:43.73996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.255115ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:08:43.740673Z","caller":"traceutil/trace.go:171","msg":"trace[1922352969] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"317.94857ms","start":"2026-06-09T17:08:43.422686Z","end":"2026-06-09T17:08:43.740634Z","steps":["trace[1922352969] 'process raft request' (duration: 92.860642ms)","trace[1922352969] 'compare' (duration: 221.903677ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:08:43.740726Z","caller":"traceutil/trace.go:171","msg":"trace[1598659382] linearizableReadLoop","detail":"{readStateIndex:4921; appliedIndex:4917; }","duration":"225.434675ms","start":"2026-06-09T17:08:43.515276Z","end":"2026-06-09T17:08:43.740711Z","steps":["trace[1598659382] 'read index received' (duration: 220.384µs)","trace[1598659382] 'applied index is now lower than readState.Index' (duration: 225.212811ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:08:43.74073Z","caller":"traceutil/trace.go:171","msg":"trace[2066867592] transaction","detail":"{read_only:false; response_revision:4742; number_of_response:1; }","duration":"316.380546ms","start":"2026-06-09T17:08:43.424325Z","end":"2026-06-09T17:08:43.740706Z","steps":["trace[2066867592] 'process raft request' (duration: 316.175922ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:43.740921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.088544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:1 size:1321"} {"level":"info","ts":"2026-06-09T17:08:43.740607Z","caller":"traceutil/trace.go:171","msg":"trace[1178805758] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"317.842728ms","start":"2026-06-09T17:08:43.422746Z","end":"2026-06-09T17:08:43.740589Z","steps":["trace[1178805758] 'process raft request' (duration: 317.612395ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:43.740957Z","caller":"traceutil/trace.go:171","msg":"trace[1800700912] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:4743; }","duration":"315.143766ms","start":"2026-06-09T17:08:43.425804Z","end":"2026-06-09T17:08:43.740948Z","steps":["trace[1800700912] 'agreement among raft nodes before linearized reading' (duration: 315.007083ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:43.740671Z","caller":"traceutil/trace.go:171","msg":"trace[1469869587] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"163.197399ms","start":"2026-06-09T17:08:43.577447Z","end":"2026-06-09T17:08:43.740645Z","steps":["trace[1469869587] 'process raft request' (duration: 163.124378ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:43.740992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:43.424318Z","time spent":"316.574708ms","remote":"127.0.0.1:37946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":906,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:08:43.741001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.522538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-79cdc7bddb-jxpvr.18b778bb13454cc2\" ","response":"range_response_count:1 size:896"} {"level":"warn","ts":"2026-06-09T17:08:43.740978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:43.425781Z","time spent":"315.191716ms","remote":"127.0.0.1:37890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1344,"request content":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" "} {"level":"info","ts":"2026-06-09T17:08:43.74105Z","caller":"traceutil/trace.go:171","msg":"trace[2031498431] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-79cdc7bddb-jxpvr.18b778bb13454cc2; range_end:; response_count:1; response_revision:4743; }","duration":"206.572699ms","start":"2026-06-09T17:08:43.534461Z","end":"2026-06-09T17:08:43.741034Z","steps":["trace[2031498431] 'agreement among raft nodes before linearized reading' (duration: 206.374626ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:43.741235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.299235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-06-09T17:08:43.743598Z","caller":"traceutil/trace.go:171","msg":"trace[1817486939] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4743; }","duration":"208.723664ms","start":"2026-06-09T17:08:43.534841Z","end":"2026-06-09T17:08:43.743564Z","steps":["trace[1817486939] 'agreement among raft nodes before linearized reading' (duration: 206.073542ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:43.74361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:43.422676Z","time spent":"318.039091ms","remote":"127.0.0.1:37946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1283,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:08:43.741409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:43.422741Z","time spent":"318.216005ms","remote":"127.0.0.1:38058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1455,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:08:47.075307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.572091ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:08:47.075548Z","caller":"traceutil/trace.go:171","msg":"trace[990263053] transaction","detail":"{read_only:false; response_revision:4780; number_of_response:1; }","duration":"449.20188ms","start":"2026-06-09T17:08:46.626313Z","end":"2026-06-09T17:08:47.075515Z","steps":["trace[990263053] 'process raft request' (duration: 234.279811ms)","trace[990263053] 'compare' (duration: 214.3891ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:08:47.075637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:46.626301Z","time spent":"449.301432ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:08:47.076134Z","caller":"traceutil/trace.go:171","msg":"trace[884773757] linearizableReadLoop","detail":"{readStateIndex:4961; appliedIndex:4958; }","duration":"344.815865ms","start":"2026-06-09T17:08:46.731281Z","end":"2026-06-09T17:08:47.076097Z","steps":["trace[884773757] 'read index received' (duration: 129.168736ms)","trace[884773757] 'applied index is now lower than readState.Index' (duration: 215.645819ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:08:47.076159Z","caller":"traceutil/trace.go:171","msg":"trace[985029712] transaction","detail":"{read_only:false; response_revision:4782; number_of_response:1; }","duration":"449.665077ms","start":"2026-06-09T17:08:46.62644Z","end":"2026-06-09T17:08:47.076105Z","steps":["trace[985029712] 'process raft request' (duration: 449.478174ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:47.076152Z","caller":"traceutil/trace.go:171","msg":"trace[2103481672] transaction","detail":"{read_only:false; response_revision:4781; number_of_response:1; }","duration":"449.760059ms","start":"2026-06-09T17:08:46.626339Z","end":"2026-06-09T17:08:47.076099Z","steps":["trace[2103481672] 'process raft request' (duration: 449.115169ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:47.076507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.205382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:08:47.076559Z","caller":"traceutil/trace.go:171","msg":"trace[1643756624] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:4782; }","duration":"345.323933ms","start":"2026-06-09T17:08:46.731222Z","end":"2026-06-09T17:08:47.076546Z","steps":["trace[1643756624] 'agreement among raft nodes before linearized reading' (duration: 345.190731ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:47.076559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:46.626423Z","time spent":"450.012334ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:08:47.076602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:46.731204Z","time spent":"345.388544ms","remote":"127.0.0.1:32984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true "} {"level":"warn","ts":"2026-06-09T17:08:47.076801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:46.626313Z","time spent":"450.368578ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:08:47.077718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.273724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2520e47f-9226-406a-8fd7-fa47306d48d1\" ","response":"range_response_count:1 size:3954"} {"level":"info","ts":"2026-06-09T17:08:47.077786Z","caller":"traceutil/trace.go:171","msg":"trace[1040516922] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2520e47f-9226-406a-8fd7-fa47306d48d1; range_end:; response_count:1; response_revision:4782; }","duration":"321.366276ms","start":"2026-06-09T17:08:46.756403Z","end":"2026-06-09T17:08:47.077769Z","steps":["trace[1040516922] 'agreement among raft nodes before linearized reading' (duration: 320.212318ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:47.077827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:46.756385Z","time spent":"321.432047ms","remote":"127.0.0.1:37962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3977,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2520e47f-9226-406a-8fd7-fa47306d48d1\" "} {"level":"info","ts":"2026-06-09T17:08:53.281585Z","caller":"traceutil/trace.go:171","msg":"trace[505817870] linearizableReadLoop","detail":"{readStateIndex:5052; appliedIndex:5051; }","duration":"179.102099ms","start":"2026-06-09T17:08:53.102436Z","end":"2026-06-09T17:08:53.281538Z","steps":["trace[505817870] 'read index received' (duration: 134.816557ms)","trace[505817870] 'applied index is now lower than readState.Index' (duration: 44.284142ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:08:53.281768Z","caller":"traceutil/trace.go:171","msg":"trace[1755238422] transaction","detail":"{read_only:false; response_revision:4869; number_of_response:1; }","duration":"192.289122ms","start":"2026-06-09T17:08:53.089463Z","end":"2026-06-09T17:08:53.281752Z","steps":["trace[1755238422] 'process raft request' (duration: 147.794677ms)","trace[1755238422] 'compare' (duration: 44.102599ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:08:53.282066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.889976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-09T17:08:53.282124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.668469ms","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-06-09T17:08:53.282153Z","caller":"traceutil/trace.go:171","msg":"trace[698596394] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4869; }","duration":"179.008538ms","start":"2026-06-09T17:08:53.103123Z","end":"2026-06-09T17:08:53.282131Z","steps":["trace[698596394] 'agreement among raft nodes before linearized reading' (duration: 178.799674ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:53.282187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.606622ms","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":"info","ts":"2026-06-09T17:08:53.282207Z","caller":"traceutil/trace.go:171","msg":"trace[94708179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4869; }","duration":"179.807432ms","start":"2026-06-09T17:08:53.10238Z","end":"2026-06-09T17:08:53.282188Z","steps":["trace[94708179] 'agreement among raft nodes before linearized reading' (duration: 179.546557ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:53.282263Z","caller":"traceutil/trace.go:171","msg":"trace[1581114493] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4869; }","duration":"173.718974ms","start":"2026-06-09T17:08:53.108528Z","end":"2026-06-09T17:08:53.282247Z","steps":["trace[1581114493] 'agreement among raft nodes before linearized reading' (duration: 173.548751ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:53.282848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.412558ms","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-06-09T17:08:53.282926Z","caller":"traceutil/trace.go:171","msg":"trace[1008804341] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4869; }","duration":"173.5263ms","start":"2026-06-09T17:08:53.109379Z","end":"2026-06-09T17:08:53.282905Z","steps":["trace[1008804341] 'agreement among raft nodes before linearized reading' (duration: 172.672966ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:53.595639Z","caller":"traceutil/trace.go:171","msg":"trace[704063224] transaction","detail":"{read_only:false; response_revision:4880; number_of_response:1; }","duration":"140.250295ms","start":"2026-06-09T17:08:53.455359Z","end":"2026-06-09T17:08:53.595609Z","steps":["trace[704063224] 'process raft request' (duration: 139.205209ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:58.522122Z","caller":"traceutil/trace.go:171","msg":"trace[37726887] linearizableReadLoop","detail":"{readStateIndex:5105; appliedIndex:5104; }","duration":"214.645473ms","start":"2026-06-09T17:08:58.307451Z","end":"2026-06-09T17:08:58.522097Z","steps":["trace[37726887] 'read index received' (duration: 214.553011ms)","trace[37726887] 'applied index is now lower than readState.Index' (duration: 90.712µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:08:58.522195Z","caller":"traceutil/trace.go:171","msg":"trace[981439973] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"238.722949ms","start":"2026-06-09T17:08:58.283426Z","end":"2026-06-09T17:08:58.522149Z","steps":["trace[981439973] 'process raft request' (duration: 238.517616ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:58.522419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.940407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T17:08:58.522917Z","caller":"traceutil/trace.go:171","msg":"trace[156681947] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:4921; }","duration":"215.481517ms","start":"2026-06-09T17:08:58.307417Z","end":"2026-06-09T17:08:58.522898Z","steps":["trace[156681947] 'agreement among raft nodes before linearized reading' (duration: 214.860857ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:58.550863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.675789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.179\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-09T17:08:58.550924Z","caller":"traceutil/trace.go:171","msg":"trace[2091642973] range","detail":"{range_begin:/registry/masterleases/199.19.213.179; range_end:; response_count:1; response_revision:4922; }","duration":"200.790942ms","start":"2026-06-09T17:08:58.350116Z","end":"2026-06-09T17:08:58.550907Z","steps":["trace[2091642973] 'agreement among raft nodes before linearized reading' (duration: 200.611319ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:58.974598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.253299ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-06-09T17:08:58.974918Z","caller":"traceutil/trace.go:171","msg":"trace[21971632] linearizableReadLoop","detail":"{readStateIndex:5108; appliedIndex:5106; }","duration":"301.464535ms","start":"2026-06-09T17:08:58.673434Z","end":"2026-06-09T17:08:58.974899Z","steps":["trace[21971632] 'read index received' (duration: 241.859459ms)","trace[21971632] 'applied index is now lower than readState.Index' (duration: 59.604146ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:08:58.975467Z","caller":"traceutil/trace.go:171","msg":"trace[631656551] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"307.604545ms","start":"2026-06-09T17:08:58.667774Z","end":"2026-06-09T17:08:58.975379Z","steps":["trace[631656551] 'process raft request' (duration: 306.948454ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:58.975312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:58.552084Z","time spent":"423.219679ms","remote":"127.0.0.1:37820","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-09T17:08:58.975809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.372721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-09T17:08:58.975852Z","caller":"traceutil/trace.go:171","msg":"trace[1070049652] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4923; }","duration":"302.478512ms","start":"2026-06-09T17:08:58.673363Z","end":"2026-06-09T17:08:58.975842Z","steps":["trace[1070049652] 'agreement among raft nodes before linearized reading' (duration: 301.961263ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:58.97588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:58.673327Z","time spent":"302.546693ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-09T17:08:58.975862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:08:58.667746Z","time spent":"307.856869ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:08:58.981696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.334805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-06-09T17:08:58.981738Z","caller":"traceutil/trace.go:171","msg":"trace[1714757117] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4924; }","duration":"197.450388ms","start":"2026-06-09T17:08:58.784277Z","end":"2026-06-09T17:08:58.981728Z","steps":["trace[1714757117] 'agreement among raft nodes before linearized reading' (duration: 197.313976ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:58.981746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.622933ms","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-06-09T17:08:58.981983Z","caller":"traceutil/trace.go:171","msg":"trace[1580986218] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"228.353293ms","start":"2026-06-09T17:08:58.75359Z","end":"2026-06-09T17:08:58.981943Z","steps":["trace[1580986218] 'process raft request' (duration: 227.734243ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:58.982129Z","caller":"traceutil/trace.go:171","msg":"trace[743783113] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4924; }","duration":"295.756815ms","start":"2026-06-09T17:08:58.686035Z","end":"2026-06-09T17:08:58.981792Z","steps":["trace[743783113] 'agreement among raft nodes before linearized reading' (duration: 295.46807ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:58.982123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.196095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-06-09T17:08:58.982249Z","caller":"traceutil/trace.go:171","msg":"trace[74904927] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4924; }","duration":"290.352788ms","start":"2026-06-09T17:08:58.691872Z","end":"2026-06-09T17:08:58.982224Z","steps":["trace[74904927] 'agreement among raft nodes before linearized reading' (duration: 290.002183ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:58.982272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.07286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:17233"} {"level":"info","ts":"2026-06-09T17:08:58.982309Z","caller":"traceutil/trace.go:171","msg":"trace[957800494] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4924; }","duration":"291.134791ms","start":"2026-06-09T17:08:58.691168Z","end":"2026-06-09T17:08:58.982303Z","steps":["trace[957800494] 'agreement among raft nodes before linearized reading' (duration: 291.043859ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:59.110236Z","caller":"traceutil/trace.go:171","msg":"trace[1994527069] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"122.666848ms","start":"2026-06-09T17:08:58.987537Z","end":"2026-06-09T17:08:59.110204Z","steps":["trace[1994527069] 'process raft request' (duration: 95.599204ms)","trace[1994527069] 'compare' (duration: 26.86409ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:08:59.11069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.62232ms","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-06-09T17:08:59.110772Z","caller":"traceutil/trace.go:171","msg":"trace[1371924224] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:4928; }","duration":"121.738003ms","start":"2026-06-09T17:08:58.989015Z","end":"2026-06-09T17:08:59.110753Z","steps":["trace[1371924224] 'agreement among raft nodes before linearized reading' (duration: 121.5944ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:59.110983Z","caller":"traceutil/trace.go:171","msg":"trace[263458894] transaction","detail":"{read_only:false; response_revision:4928; number_of_response:1; }","duration":"115.242819ms","start":"2026-06-09T17:08:58.99573Z","end":"2026-06-09T17:08:59.110972Z","steps":["trace[263458894] 'process raft request' (duration: 114.401335ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:08:59.111129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.642301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:1 size:1321"} {"level":"info","ts":"2026-06-09T17:08:59.111149Z","caller":"traceutil/trace.go:171","msg":"trace[1600853973] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:4928; }","duration":"121.677042ms","start":"2026-06-09T17:08:58.989465Z","end":"2026-06-09T17:08:59.111142Z","steps":["trace[1600853973] 'agreement among raft nodes before linearized reading' (duration: 121.58534ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:59.526006Z","caller":"traceutil/trace.go:171","msg":"trace[1752842875] transaction","detail":"{read_only:false; response_revision:4935; number_of_response:1; }","duration":"115.592493ms","start":"2026-06-09T17:08:59.41039Z","end":"2026-06-09T17:08:59.525983Z","steps":["trace[1752842875] 'process raft request' (duration: 115.41462ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:08:59.601453Z","caller":"traceutil/trace.go:171","msg":"trace[12748507] transaction","detail":"{read_only:false; response_revision:4936; number_of_response:1; }","duration":"102.685267ms","start":"2026-06-09T17:08:59.498738Z","end":"2026-06-09T17:08:59.601424Z","steps":["trace[12748507] 'process raft request' (duration: 90.38795ms)","trace[12748507] 'compare' (duration: 12.160764ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:10:15.212218Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2869} {"level":"info","ts":"2026-06-09T17:10:15.380827Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2869,"took":"166.453404ms","hash":643874293,"current-db-size-bytes":54308864,"current-db-size":"54 MB","current-db-size-in-use-bytes":29052928,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-09T17:10:15.380899Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":643874293,"revision":2869,"compact-revision":-1} {"level":"info","ts":"2026-06-09T17:12:36.55386Z","caller":"traceutil/trace.go:171","msg":"trace[566031916] transaction","detail":"{read_only:false; response_revision:6494; number_of_response:1; }","duration":"104.983502ms","start":"2026-06-09T17:12:36.448802Z","end":"2026-06-09T17:12:36.553786Z","steps":["trace[566031916] 'process raft request' (duration: 83.070493ms)","trace[566031916] 'compare' (duration: 21.711633ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:12:37.549917Z","caller":"traceutil/trace.go:171","msg":"trace[408671136] transaction","detail":"{read_only:false; response_revision:6504; number_of_response:1; }","duration":"108.129147ms","start":"2026-06-09T17:12:37.44176Z","end":"2026-06-09T17:12:37.549889Z","steps":["trace[408671136] 'process raft request' (duration: 107.640682ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:12:48.979499Z","caller":"traceutil/trace.go:171","msg":"trace[840658150] transaction","detail":"{read_only:false; response_revision:6565; number_of_response:1; }","duration":"215.16117ms","start":"2026-06-09T17:12:48.764312Z","end":"2026-06-09T17:12:48.979473Z","steps":["trace[840658150] 'process raft request' (duration: 215.032876ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:12:48.992829Z","caller":"traceutil/trace.go:171","msg":"trace[1341798913] linearizableReadLoop","detail":"{readStateIndex:6806; appliedIndex:6804; }","duration":"201.833924ms","start":"2026-06-09T17:12:48.790966Z","end":"2026-06-09T17:12:48.9928Z","steps":["trace[1341798913] 'read index received' (duration: 188.419556ms)","trace[1341798913] 'applied index is now lower than readState.Index' (duration: 13.412688ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:12:48.992913Z","caller":"traceutil/trace.go:171","msg":"trace[1242238810] transaction","detail":"{read_only:false; response_revision:6566; number_of_response:1; }","duration":"227.012624ms","start":"2026-06-09T17:12:48.765855Z","end":"2026-06-09T17:12:48.992868Z","steps":["trace[1242238810] 'process raft request' (duration: 226.778597ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:12:48.993063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.02429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T17:12:48.993126Z","caller":"traceutil/trace.go:171","msg":"trace[965532256] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:6566; }","duration":"202.178605ms","start":"2026-06-09T17:12:48.79093Z","end":"2026-06-09T17:12:48.993108Z","steps":["trace[965532256] 'agreement among raft nodes before linearized reading' (duration: 202.01356ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:12:49.649963Z","caller":"traceutil/trace.go:171","msg":"trace[1153807605] transaction","detail":"{read_only:false; response_revision:6567; number_of_response:1; }","duration":"170.539027ms","start":"2026-06-09T17:12:49.479386Z","end":"2026-06-09T17:12:49.649925Z","steps":["trace[1153807605] 'process raft request' (duration: 170.30206ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:12:49.650428Z","caller":"traceutil/trace.go:171","msg":"trace[1944439634] linearizableReadLoop","detail":"{readStateIndex:6807; appliedIndex:6807; }","duration":"143.169146ms","start":"2026-06-09T17:12:49.507218Z","end":"2026-06-09T17:12:49.650387Z","steps":["trace[1944439634] 'read index received' (duration: 143.154676ms)","trace[1944439634] 'applied index is now lower than readState.Index' (duration: 10.8µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:12:49.650596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.348581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:12:49.650654Z","caller":"traceutil/trace.go:171","msg":"trace[394479076] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:6567; }","duration":"143.478985ms","start":"2026-06-09T17:12:49.50716Z","end":"2026-06-09T17:12:49.650639Z","steps":["trace[394479076] 'agreement among raft nodes before linearized reading' (duration: 143.360132ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:12:50.542965Z","caller":"traceutil/trace.go:171","msg":"trace[911829662] transaction","detail":"{read_only:false; response_revision:6572; number_of_response:1; }","duration":"218.082113ms","start":"2026-06-09T17:12:50.324823Z","end":"2026-06-09T17:12:50.542905Z","steps":["trace[911829662] 'process raft request' (duration: 168.725758ms)","trace[911829662] 'compare' (duration: 48.733258ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:13:14.966172Z","caller":"traceutil/trace.go:171","msg":"trace[803313668] transaction","detail":"{read_only:false; response_revision:6757; number_of_response:1; }","duration":"185.79725ms","start":"2026-06-09T17:13:14.780312Z","end":"2026-06-09T17:13:14.966109Z","steps":["trace[803313668] 'process raft request' (duration: 117.740764ms)","trace[803313668] 'compare' (duration: 67.692867ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:13:14.966418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.111873ms","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-06-09T17:13:14.966148Z","caller":"traceutil/trace.go:171","msg":"trace[329800733] linearizableReadLoop","detail":"{readStateIndex:7005; appliedIndex:7004; }","duration":"166.854106ms","start":"2026-06-09T17:13:14.799272Z","end":"2026-06-09T17:13:14.966126Z","steps":["trace[329800733] 'read index received' (duration: 98.756328ms)","trace[329800733] 'applied index is now lower than readState.Index' (duration: 68.096508ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:13:14.966459Z","caller":"traceutil/trace.go:171","msg":"trace[413875002] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6757; }","duration":"167.213675ms","start":"2026-06-09T17:13:14.799238Z","end":"2026-06-09T17:13:14.966451Z","steps":["trace[413875002] 'agreement among raft nodes before linearized reading' (duration: 166.949568ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:13:14.966806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.648526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-09T17:13:14.966926Z","caller":"traceutil/trace.go:171","msg":"trace[695180298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6758; }","duration":"164.78887ms","start":"2026-06-09T17:13:14.802108Z","end":"2026-06-09T17:13:14.966897Z","steps":["trace[695180298] 'agreement among raft nodes before linearized reading' (duration: 164.447861ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:15:15.218841Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5535} {"level":"info","ts":"2026-06-09T17:15:15.29964Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5535,"took":"78.557035ms","hash":3457846470,"current-db-size-bytes":54308864,"current-db-size":"54 MB","current-db-size-in-use-bytes":18247680,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-06-09T17:15:15.299911Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3457846470,"revision":5535,"compact-revision":2869}