{"level":"warn","ts":"2026-05-14T14:18:58.546664Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-14T14:18:58.546871Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-14T14:18:58.54698Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-14T14:18:58.546995Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-05-14T14:18:58.54704Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-14T14:18:58.548292Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-05-14T14:18:58.54838Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-14T14:18:58.552585Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.966596ms"} {"level":"info","ts":"2026-05-14T14:18:58.559153Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-05-14T14:18:58.559258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-05-14T14:18:58.559285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-05-14T14:18:58.559302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-14T14:18:58.559314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-05-14T14:18:58.559354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-05-14T14:18:58.565597Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-14T14:18:58.567039Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-14T14:18:58.567907Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-14T14:18:58.570091Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-14T14:18:58.571771Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-14T14:18:58.574829Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-14T14:18:58.574948Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-14T14:18:58.575026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-05-14T14:18:58.575703Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-05-14T14:18:58.57497Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-14T14:18:58.577008Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-14T14:18:58.57712Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-05-14T14:18:58.577173Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-05-14T14:18:58.577421Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-14T14:18:58.577485Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-14T14:18:59.060072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-05-14T14:18:59.060174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-05-14T14:18:59.060463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-05-14T14:18:59.060547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-05-14T14:18:59.060969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-05-14T14:18:59.06102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-05-14T14:18:59.061179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-05-14T14:18:59.065887Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-05-14T14:18:59.065917Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-14T14:18:59.06613Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-14T14:18:59.066364Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-14T14:18:59.066411Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-14T14:18:59.066502Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T14:18:59.068729Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-14T14:18:59.068894Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T14:18:59.070853Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T14:18:59.070946Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T14:18:59.069491Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"info","ts":"2026-05-14T14:19:02.06532Z","caller":"traceutil/trace.go:171","msg":"trace[1340671602] transaction","detail":"{read_only:false; response_revision:158; number_of_response:1; }","duration":"142.393499ms","start":"2026-05-14T14:19:01.922881Z","end":"2026-05-14T14:19:02.065274Z","steps":["trace[1340671602] 'process raft request' (duration: 47.335284ms)","trace[1340671602] 'compare' (duration: 94.521453ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:19:02.320391Z","caller":"traceutil/trace.go:171","msg":"trace[1157574336] transaction","detail":"{read_only:false; response_revision:172; number_of_response:1; }","duration":"163.714394ms","start":"2026-05-14T14:19:02.156638Z","end":"2026-05-14T14:19:02.320352Z","steps":["trace[1157574336] 'process raft request' (duration: 109.829272ms)","trace[1157574336] 'compare' (duration: 53.686263ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:19:02.521859Z","caller":"traceutil/trace.go:171","msg":"trace[1318657396] transaction","detail":"{read_only:false; response_revision:176; number_of_response:1; }","duration":"101.851185ms","start":"2026-05-14T14:19:02.419986Z","end":"2026-05-14T14:19:02.521837Z","steps":["trace[1318657396] 'process raft request' (duration: 31.08204ms)","trace[1318657396] 'compare' (duration: 70.618228ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:19:02.579563Z","caller":"traceutil/trace.go:171","msg":"trace[1765480522] transaction","detail":"{read_only:false; response_revision:178; number_of_response:1; }","duration":"115.584344ms","start":"2026-05-14T14:19:02.463941Z","end":"2026-05-14T14:19:02.579526Z","steps":["trace[1765480522] 'process raft request' (duration: 115.427727ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:03.093888Z","caller":"traceutil/trace.go:171","msg":"trace[876478944] transaction","detail":"{read_only:false; response_revision:190; number_of_response:1; }","duration":"105.141921ms","start":"2026-05-14T14:19:02.988718Z","end":"2026-05-14T14:19:03.093859Z","steps":["trace[876478944] 'process raft request' (duration: 104.771475ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:07.727971Z","caller":"traceutil/trace.go:171","msg":"trace[568260685] linearizableReadLoop","detail":"{readStateIndex:350; appliedIndex:349; }","duration":"147.83146ms","start":"2026-05-14T14:19:07.580109Z","end":"2026-05-14T14:19:07.727941Z","steps":["trace[568260685] 'read index received' (duration: 65.139307ms)","trace[568260685] 'applied index is now lower than readState.Index' (duration: 82.691043ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:19:07.728147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.003156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pvc-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:19:07.728212Z","caller":"traceutil/trace.go:171","msg":"trace[2138473089] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pvc-protection-controller; range_end:; response_count:0; response_revision:335; }","duration":"148.114231ms","start":"2026-05-14T14:19:07.580085Z","end":"2026-05-14T14:19:07.728199Z","steps":["trace[2138473089] 'agreement among raft nodes before linearized reading' (duration: 147.961725ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:07.728555Z","caller":"traceutil/trace.go:171","msg":"trace[50033835] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"171.84093ms","start":"2026-05-14T14:19:07.556702Z","end":"2026-05-14T14:19:07.728542Z","steps":["trace[50033835] 'process raft request' (duration: 88.698378ms)","trace[50033835] 'compare' (duration: 82.412801ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:19:15.136568Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219825085514391,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T14:19:15.637105Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219825085514391,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T14:19:15.918631Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.454155036s","expected-duration":"1s"} {"level":"info","ts":"2026-05-14T14:19:15.91915Z","caller":"traceutil/trace.go:171","msg":"trace[1277768072] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"1.454800334s","start":"2026-05-14T14:19:14.464318Z","end":"2026-05-14T14:19:15.919118Z","steps":["trace[1277768072] 'process raft request' (duration: 1.454592824s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:15.920048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:14.464291Z","time spent":"1.454961132s","remote":"127.0.0.1:36486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4307,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T14:19:15.921863Z","caller":"traceutil/trace.go:171","msg":"trace[1903467558] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:423; }","duration":"1.286248676s","start":"2026-05-14T14:19:14.635594Z","end":"2026-05-14T14:19:15.921842Z","steps":["trace[1903467558] 'read index received' (duration: 1.283308699s)","trace[1903467558] 'applied index is now lower than readState.Index' (duration: 2.939007ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:19:15.922092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.12760096s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T14:19:15.922141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.286524998s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-14T14:19:15.922144Z","caller":"traceutil/trace.go:171","msg":"trace[1111722065] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"515.939943ms","start":"2026-05-14T14:19:15.406186Z","end":"2026-05-14T14:19:15.922126Z","steps":["trace[1111722065] 'process raft request' (duration: 515.562576ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:15.922204Z","caller":"traceutil/trace.go:171","msg":"trace[989173907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:407; }","duration":"1.286662733s","start":"2026-05-14T14:19:14.635525Z","end":"2026-05-14T14:19:15.922188Z","steps":["trace[989173907] 'agreement among raft nodes before linearized reading' (duration: 1.286507347s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:15.922217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.456606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T14:19:15.922245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:14.635501Z","time spent":"1.286738636s","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-14T14:19:15.922257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:15.40615Z","time spent":"516.051887ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T14:19:15.922301Z","caller":"traceutil/trace.go:171","msg":"trace[97084763] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:407; }","duration":"805.615484ms","start":"2026-05-14T14:19:15.116658Z","end":"2026-05-14T14:19:15.922274Z","steps":["trace[97084763] 'agreement among raft nodes before linearized reading' (duration: 805.422826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:15.922385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:15.116623Z","time spent":"805.751739ms","remote":"127.0.0.1:36278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T14:19:15.922409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.220067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-14T14:19:15.922431Z","caller":"traceutil/trace.go:171","msg":"trace[1375311062] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:407; }","duration":"875.28876ms","start":"2026-05-14T14:19:15.047135Z","end":"2026-05-14T14:19:15.922424Z","steps":["trace[1375311062] 'agreement among raft nodes before linearized reading' (duration: 875.251629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:15.922469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:15.047105Z","time spent":"875.360074ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-14T14:19:15.922467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.019811832s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-14T14:19:15.922452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.785875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-14T14:19:15.922605Z","caller":"traceutil/trace.go:171","msg":"trace[10935794] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:407; }","duration":"123.014625ms","start":"2026-05-14T14:19:15.799569Z","end":"2026-05-14T14:19:15.922583Z","steps":["trace[10935794] 'agreement among raft nodes before linearized reading' (duration: 122.575315ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:15.922529Z","caller":"traceutil/trace.go:171","msg":"trace[1271415890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:407; }","duration":"1.019908037s","start":"2026-05-14T14:19:14.902605Z","end":"2026-05-14T14:19:15.922513Z","steps":["trace[1271415890] 'agreement among raft nodes before linearized reading' (duration: 1.019807713s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:15.922752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:14.902583Z","time spent":"1.020120306s","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-14T14:19:15.92243Z","caller":"traceutil/trace.go:171","msg":"trace[1347354606] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:407; }","duration":"1.127693944s","start":"2026-05-14T14:19:14.794475Z","end":"2026-05-14T14:19:15.922169Z","steps":["trace[1347354606] 'agreement among raft nodes before linearized reading' (duration: 1.127583419s)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:32.31678Z","caller":"traceutil/trace.go:171","msg":"trace[1197360180] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"284.73352ms","start":"2026-05-14T14:19:32.032015Z","end":"2026-05-14T14:19:32.316748Z","steps":["trace[1197360180] 'process raft request' (duration: 284.440527ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:32.316977Z","caller":"traceutil/trace.go:171","msg":"trace[1432562314] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"277.546803ms","start":"2026-05-14T14:19:32.039389Z","end":"2026-05-14T14:19:32.316936Z","steps":["trace[1432562314] 'process raft request' (duration: 277.270361ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:32.317105Z","caller":"traceutil/trace.go:171","msg":"trace[2063504869] linearizableReadLoop","detail":"{readStateIndex:502; appliedIndex:500; }","duration":"199.009729ms","start":"2026-05-14T14:19:32.117923Z","end":"2026-05-14T14:19:32.316933Z","steps":["trace[2063504869] 'read index received' (duration: 198.399524ms)","trace[2063504869] 'applied index is now lower than readState.Index' (duration: 608.065µs)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:19:32.317126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.17818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:19:32.317228Z","caller":"traceutil/trace.go:171","msg":"trace[932469839] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:481; }","duration":"199.339472ms","start":"2026-05-14T14:19:32.117871Z","end":"2026-05-14T14:19:32.31721Z","steps":["trace[932469839] 'agreement among raft nodes before linearized reading' (duration: 199.163481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:34.372472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.901361ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-14T14:19:34.372675Z","caller":"traceutil/trace.go:171","msg":"trace[491972371] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"203.77326ms","start":"2026-05-14T14:19:34.168889Z","end":"2026-05-14T14:19:34.372662Z","steps":["trace[491972371] 'process raft request' (duration: 203.68493ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:34.372681Z","caller":"traceutil/trace.go:171","msg":"trace[1213695299] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"203.886131ms","start":"2026-05-14T14:19:34.168771Z","end":"2026-05-14T14:19:34.372657Z","steps":["trace[1213695299] 'process raft request' (duration: 102.616937ms)","trace[1213695299] 'compare' (duration: 100.764289ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:19:36.356807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.946741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-14T14:19:36.356903Z","caller":"traceutil/trace.go:171","msg":"trace[119131632] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"198.15766ms","start":"2026-05-14T14:19:36.158727Z","end":"2026-05-14T14:19:36.356885Z","steps":["trace[119131632] 'process raft request' (duration: 21.054009ms)","trace[119131632] 'compare' (duration: 176.834759ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:19:36.365612Z","caller":"traceutil/trace.go:171","msg":"trace[1428086227] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"199.808866ms","start":"2026-05-14T14:19:36.165792Z","end":"2026-05-14T14:19:36.365601Z","steps":["trace[1428086227] 'process raft request' (duration: 199.788336ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:36.365768Z","caller":"traceutil/trace.go:171","msg":"trace[229369414] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"200.807726ms","start":"2026-05-14T14:19:36.164937Z","end":"2026-05-14T14:19:36.365744Z","steps":["trace[229369414] 'process raft request' (duration: 200.572914ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:36.365765Z","caller":"traceutil/trace.go:171","msg":"trace[382579445] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"200.670115ms","start":"2026-05-14T14:19:36.165087Z","end":"2026-05-14T14:19:36.365757Z","steps":["trace[382579445] 'process raft request' (duration: 200.472813ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:36.365808Z","caller":"traceutil/trace.go:171","msg":"trace[413715683] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"200.720566ms","start":"2026-05-14T14:19:36.165058Z","end":"2026-05-14T14:19:36.365778Z","steps":["trace[413715683] 'process raft request' (duration: 200.481194ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:36.366839Z","caller":"traceutil/trace.go:171","msg":"trace[400802067] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"201.823547ms","start":"2026-05-14T14:19:36.164879Z","end":"2026-05-14T14:19:36.366703Z","steps":["trace[400802067] 'process raft request' (duration: 200.594165ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:36.367602Z","caller":"traceutil/trace.go:171","msg":"trace[229534535] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"202.782416ms","start":"2026-05-14T14:19:36.164788Z","end":"2026-05-14T14:19:36.36757Z","steps":["trace[229534535] 'process raft request' (duration: 200.621315ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:36.37242Z","caller":"traceutil/trace.go:171","msg":"trace[1187661130] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"149.656451ms","start":"2026-05-14T14:19:36.221597Z","end":"2026-05-14T14:19:36.371254Z","steps":["trace[1187661130] 'process raft request' (duration: 149.50441ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:36.372877Z","caller":"traceutil/trace.go:171","msg":"trace[1145383873] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"190.600165ms","start":"2026-05-14T14:19:36.182263Z","end":"2026-05-14T14:19:36.372863Z","steps":["trace[1145383873] 'process raft request' (duration: 188.325592ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:36.37322Z","caller":"traceutil/trace.go:171","msg":"trace[848883194] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"186.081591ms","start":"2026-05-14T14:19:36.187128Z","end":"2026-05-14T14:19:36.37321Z","steps":["trace[848883194] 'process raft request' (duration: 183.626626ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:36.374749Z","caller":"traceutil/trace.go:171","msg":"trace[1920660004] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"180.731117ms","start":"2026-05-14T14:19:36.190542Z","end":"2026-05-14T14:19:36.371273Z","steps":["trace[1920660004] 'process raft request' (duration: 180.292103ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:36.375045Z","caller":"traceutil/trace.go:171","msg":"trace[47948130] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"178.9064ms","start":"2026-05-14T14:19:36.192372Z","end":"2026-05-14T14:19:36.371279Z","steps":["trace[47948130] 'process raft request' (duration: 178.537747ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:36.375139Z","caller":"traceutil/trace.go:171","msg":"trace[847169844] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"166.83511ms","start":"2026-05-14T14:19:36.204448Z","end":"2026-05-14T14:19:36.371284Z","steps":["trace[847169844] 'process raft request' (duration: 166.560398ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:36.847087Z","caller":"traceutil/trace.go:171","msg":"trace[1950096236] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"388.754136ms","start":"2026-05-14T14:19:36.458291Z","end":"2026-05-14T14:19:36.847045Z","steps":["trace[1950096236] 'process raft request' (duration: 388.569474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:36.847275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:36.458269Z","time spent":"388.920097ms","remote":"127.0.0.1:36352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T14:19:36.85542Z","caller":"traceutil/trace.go:171","msg":"trace[1065568397] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"392.812217ms","start":"2026-05-14T14:19:36.462578Z","end":"2026-05-14T14:19:36.85539Z","steps":["trace[1065568397] 'process raft request' (duration: 392.721475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:36.855589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:36.462569Z","time spent":"392.934588ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T14:19:36.855841Z","caller":"traceutil/trace.go:171","msg":"trace[155475506] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"397.37196ms","start":"2026-05-14T14:19:36.458457Z","end":"2026-05-14T14:19:36.855829Z","steps":["trace[155475506] 'process raft request' (duration: 396.637663ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:36.855896Z","caller":"traceutil/trace.go:171","msg":"trace[984973597] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"343.906212ms","start":"2026-05-14T14:19:36.511972Z","end":"2026-05-14T14:19:36.855879Z","steps":["trace[984973597] 'process raft request' (duration: 343.73921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:36.85598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:36.458432Z","time spent":"397.501711ms","remote":"127.0.0.1:36352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T14:19:36.856002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:36.511918Z","time spent":"344.025482ms","remote":"127.0.0.1:36352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T14:19:36.856086Z","caller":"traceutil/trace.go:171","msg":"trace[390462512] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"389.641295ms","start":"2026-05-14T14:19:36.466439Z","end":"2026-05-14T14:19:36.85608Z","steps":["trace[390462512] 'process raft request' (duration: 388.910596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:36.856115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:36.466433Z","time spent":"389.667045ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T14:19:36.856241Z","caller":"traceutil/trace.go:171","msg":"trace[394405945] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"389.028029ms","start":"2026-05-14T14:19:36.467206Z","end":"2026-05-14T14:19:36.856234Z","steps":["trace[394405945] 'process raft request' (duration: 388.334822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:36.856273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:36.466793Z","time spent":"389.463353ms","remote":"127.0.0.1:36352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7693,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T14:19:36.856344Z","caller":"traceutil/trace.go:171","msg":"trace[821525302] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"378.297212ms","start":"2026-05-14T14:19:36.478042Z","end":"2026-05-14T14:19:36.856339Z","steps":["trace[821525302] 'process raft request' (duration: 377.568425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:36.85637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:36.478011Z","time spent":"378.343083ms","remote":"127.0.0.1:36352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T14:19:36.856406Z","caller":"traceutil/trace.go:171","msg":"trace[1928411176] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"368.130062ms","start":"2026-05-14T14:19:36.488271Z","end":"2026-05-14T14:19:36.856401Z","steps":["trace[1928411176] 'process raft request' (duration: 367.397585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:36.85643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:36.488239Z","time spent":"368.181392ms","remote":"127.0.0.1:36352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T14:19:43.618138Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219825085514872,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-14T14:19:43.68698Z","caller":"traceutil/trace.go:171","msg":"trace[259095918] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"569.711062ms","start":"2026-05-14T14:19:43.117235Z","end":"2026-05-14T14:19:43.686946Z","steps":["trace[259095918] 'read index received' (duration: 568.495236ms)","trace[259095918] 'applied index is now lower than readState.Index' (duration: 1.214466ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:19:43.687159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.894944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:19:43.687253Z","caller":"traceutil/trace.go:171","msg":"trace[819151278] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"570.039896ms","start":"2026-05-14T14:19:43.117194Z","end":"2026-05-14T14:19:43.687234Z","steps":["trace[819151278] 'agreement among raft nodes before linearized reading' (duration: 569.859064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:43.687256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.419936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-14T14:19:43.687308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:43.117175Z","time spent":"570.123867ms","remote":"127.0.0.1:36274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T14:19:43.68732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.902723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jzvft\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-14T14:19:43.687326Z","caller":"traceutil/trace.go:171","msg":"trace[386194799] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:621; }","duration":"354.520318ms","start":"2026-05-14T14:19:43.332782Z","end":"2026-05-14T14:19:43.687303Z","steps":["trace[386194799] 'agreement among raft nodes before linearized reading' (duration: 354.372816ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:43.687369Z","caller":"traceutil/trace.go:171","msg":"trace[1012281808] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jzvft; range_end:; response_count:1; response_revision:621; }","duration":"236.035685ms","start":"2026-05-14T14:19:43.451319Z","end":"2026-05-14T14:19:43.687354Z","steps":["trace[1012281808] 'agreement among raft nodes before linearized reading' (duration: 235.834362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:43.687375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:43.332763Z","time spent":"354.602639ms","remote":"127.0.0.1:36328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"info","ts":"2026-05-14T14:19:43.687076Z","caller":"traceutil/trace.go:171","msg":"trace[278271849] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"703.541098ms","start":"2026-05-14T14:19:42.98346Z","end":"2026-05-14T14:19:43.687001Z","steps":["trace[278271849] 'process raft request' (duration: 702.711867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:43.687843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:42.98341Z","time spent":"704.307417ms","remote":"127.0.0.1:36382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T14:19:45.393302Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219825085514887,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T14:19:45.455512Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.210856641s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-14T14:19:45.457787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.340977211s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:19:45.457857Z","caller":"traceutil/trace.go:171","msg":"trace[547916581] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"1.341051902s","start":"2026-05-14T14:19:44.116786Z","end":"2026-05-14T14:19:45.457837Z","steps":["trace[547916581] 'range keys from in-memory index tree' (duration: 1.34088168s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:45.457893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:44.116734Z","time spent":"1.341150373s","remote":"127.0.0.1:36278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T14:19:45.458135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.340924892s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-14T14:19:45.45817Z","caller":"traceutil/trace.go:171","msg":"trace[1831552381] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:624; }","duration":"1.340990862s","start":"2026-05-14T14:19:44.117171Z","end":"2026-05-14T14:19:45.458162Z","steps":["trace[1831552381] 'range keys from in-memory index tree' (duration: 1.34079085s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:45.458196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:44.117158Z","time spent":"1.341031483s","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T14:19:45.459454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.32142309s","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-05-14T14:19:45.459532Z","caller":"traceutil/trace.go:171","msg":"trace[935134939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:624; }","duration":"1.321509981s","start":"2026-05-14T14:19:44.137994Z","end":"2026-05-14T14:19:45.459504Z","steps":["trace[935134939] 'range keys from in-memory index tree' (duration: 1.320384575s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:45.459582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:44.13798Z","time spent":"1.321589492s","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-14T14:19:45.461739Z","caller":"traceutil/trace.go:171","msg":"trace[1598304424] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"568.987853ms","start":"2026-05-14T14:19:44.892709Z","end":"2026-05-14T14:19:45.461697Z","steps":["trace[1598304424] 'read index received' (duration: 562.955238ms)","trace[1598304424] 'applied index is now lower than readState.Index' (duration: 6.031635ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:19:45.462912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.191308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-14T14:19:45.463073Z","caller":"traceutil/trace.go:171","msg":"trace[1774340645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:625; }","duration":"570.385341ms","start":"2026-05-14T14:19:44.892654Z","end":"2026-05-14T14:19:45.463039Z","steps":["trace[1774340645] 'agreement among raft nodes before linearized reading' (duration: 570.105757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:45.463122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:44.892631Z","time spent":"570.477253ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-14T14:19:45.463353Z","caller":"traceutil/trace.go:171","msg":"trace[1214980142] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"1.221153053s","start":"2026-05-14T14:19:44.242169Z","end":"2026-05-14T14:19:45.463322Z","steps":["trace[1214980142] 'process raft request' (duration: 1.214053034s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:45.463468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:44.242138Z","time spent":"1.221259224s","remote":"127.0.0.1:36382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036165,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T14:19:45.463693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.131958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-14T14:19:45.463794Z","caller":"traceutil/trace.go:171","msg":"trace[2060795345] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:625; }","duration":"567.27208ms","start":"2026-05-14T14:19:44.896509Z","end":"2026-05-14T14:19:45.463781Z","steps":["trace[2060795345] 'agreement among raft nodes before linearized reading' (duration: 567.085817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:19:45.463908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:19:44.896487Z","time spent":"567.393022ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-14T14:19:46.142966Z","caller":"traceutil/trace.go:171","msg":"trace[857432518] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"181.044897ms","start":"2026-05-14T14:19:45.961896Z","end":"2026-05-14T14:19:46.142941Z","steps":["trace[857432518] 'process raft request' (duration: 180.479498ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:46.726247Z","caller":"traceutil/trace.go:171","msg":"trace[2114317366] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"198.412831ms","start":"2026-05-14T14:19:46.527789Z","end":"2026-05-14T14:19:46.726202Z","steps":["trace[2114317366] 'process raft request' (duration: 123.257549ms)","trace[2114317366] 'compare' (duration: 74.817688ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:19:46.996525Z","caller":"traceutil/trace.go:171","msg":"trace[1187147674] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"116.866188ms","start":"2026-05-14T14:19:46.879621Z","end":"2026-05-14T14:19:46.996488Z","steps":["trace[1187147674] 'process raft request' (duration: 74.171979ms)","trace[1187147674] 'compare' (duration: 42.349064ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:19:48.56899Z","caller":"traceutil/trace.go:171","msg":"trace[552493438] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"120.906941ms","start":"2026-05-14T14:19:48.448044Z","end":"2026-05-14T14:19:48.56895Z","steps":["trace[552493438] 'process raft request' (duration: 120.248281ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:53.791984Z","caller":"traceutil/trace.go:171","msg":"trace[5085311] linearizableReadLoop","detail":"{readStateIndex:770; appliedIndex:769; }","duration":"115.044371ms","start":"2026-05-14T14:19:53.676907Z","end":"2026-05-14T14:19:53.791951Z","steps":["trace[5085311] 'read index received' (duration: 85.838566ms)","trace[5085311] 'applied index is now lower than readState.Index' (duration: 29.204285ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:19:53.792085Z","caller":"traceutil/trace.go:171","msg":"trace[1136333676] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"169.143487ms","start":"2026-05-14T14:19:53.622893Z","end":"2026-05-14T14:19:53.792037Z","steps":["trace[1136333676] 'process raft request' (duration: 139.779829ms)","trace[1136333676] 'compare' (duration: 29.138824ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:19:53.792542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.603219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-14T14:19:53.792659Z","caller":"traceutil/trace.go:171","msg":"trace[1409570726] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:741; }","duration":"115.786764ms","start":"2026-05-14T14:19:53.676849Z","end":"2026-05-14T14:19:53.792636Z","steps":["trace[1409570726] 'agreement among raft nodes before linearized reading' (duration: 115.262995ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:55.776779Z","caller":"traceutil/trace.go:171","msg":"trace[13587762] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"105.578535ms","start":"2026-05-14T14:19:55.671171Z","end":"2026-05-14T14:19:55.77675Z","steps":["trace[13587762] 'process raft request' (duration: 27.958534ms)","trace[13587762] 'compare' (duration: 77.224504ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:19:55.776783Z","caller":"traceutil/trace.go:171","msg":"trace[180985345] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"104.927283ms","start":"2026-05-14T14:19:55.671804Z","end":"2026-05-14T14:19:55.776732Z","steps":["trace[180985345] 'process raft request' (duration: 104.826151ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:19:56.952189Z","caller":"traceutil/trace.go:171","msg":"trace[906993365] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"132.262172ms","start":"2026-05-14T14:19:56.819904Z","end":"2026-05-14T14:19:56.952166Z","steps":["trace[906993365] 'process raft request' (duration: 132.119579ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:20:03.628054Z","caller":"traceutil/trace.go:171","msg":"trace[357558684] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:836; }","duration":"283.537305ms","start":"2026-05-14T14:20:03.34449Z","end":"2026-05-14T14:20:03.628027Z","steps":["trace[357558684] 'read index received' (duration: 283.332892ms)","trace[357558684] 'applied index is now lower than readState.Index' (duration: 203.363µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:20:03.628656Z","caller":"traceutil/trace.go:171","msg":"trace[313633496] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"998.637453ms","start":"2026-05-14T14:20:02.629995Z","end":"2026-05-14T14:20:03.628632Z","steps":["trace[313633496] 'process raft request' (duration: 997.890818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:20:03.6288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:20:02.629975Z","time spent":"998.737594ms","remote":"127.0.0.1:36486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T14:20:03.629013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.842732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-14T14:20:03.629138Z","caller":"traceutil/trace.go:171","msg":"trace[1769224308] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:806; }","duration":"284.652088ms","start":"2026-05-14T14:20:03.344453Z","end":"2026-05-14T14:20:03.629105Z","steps":["trace[1769224308] 'agreement among raft nodes before linearized reading' (duration: 283.77511ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:20:34.269641Z","caller":"traceutil/trace.go:171","msg":"trace[393786031] linearizableReadLoop","detail":"{readStateIndex:1247; appliedIndex:1246; }","duration":"304.927833ms","start":"2026-05-14T14:20:33.964684Z","end":"2026-05-14T14:20:34.269612Z","steps":["trace[393786031] 'read index received' (duration: 304.698057ms)","trace[393786031] 'applied index is now lower than readState.Index' (duration: 228.646µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:20:34.269797Z","caller":"traceutil/trace.go:171","msg":"trace[1261035444] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"321.673437ms","start":"2026-05-14T14:20:33.948086Z","end":"2026-05-14T14:20:34.269759Z","steps":["trace[1261035444] 'process raft request' (duration: 321.310518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:20:34.269957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.754275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-14T14:20:34.269999Z","caller":"traceutil/trace.go:171","msg":"trace[996536366] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1208; }","duration":"302.859267ms","start":"2026-05-14T14:20:33.967128Z","end":"2026-05-14T14:20:34.269987Z","steps":["trace[996536366] 'agreement among raft nodes before linearized reading' (duration: 302.678882ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:20:34.270002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:20:33.948048Z","time spent":"321.843793ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T14:20:34.270038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:20:33.9671Z","time spent":"302.926839ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-14T14:20:34.270051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.343646ms","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-05-14T14:20:34.270099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.976351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:20:34.270158Z","caller":"traceutil/trace.go:171","msg":"trace[1825644191] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1208; }","duration":"305.608373ms","start":"2026-05-14T14:20:33.964525Z","end":"2026-05-14T14:20:34.270133Z","steps":["trace[1825644191] 'agreement among raft nodes before linearized reading' (duration: 305.252034ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:20:34.270214Z","caller":"traceutil/trace.go:171","msg":"trace[483480395] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1208; }","duration":"146.148015ms","start":"2026-05-14T14:20:34.124036Z","end":"2026-05-14T14:20:34.270184Z","steps":["trace[483480395] 'agreement among raft nodes before linearized reading' (duration: 145.967761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:20:34.270295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.595895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-14T14:20:34.270351Z","caller":"traceutil/trace.go:171","msg":"trace[1342601015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1208; }","duration":"260.75344ms","start":"2026-05-14T14:20:34.009588Z","end":"2026-05-14T14:20:34.270341Z","steps":["trace[1342601015] 'agreement among raft nodes before linearized reading' (duration: 260.657947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:20:34.270225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.203983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-14T14:20:34.270548Z","caller":"traceutil/trace.go:171","msg":"trace[2033597982] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1208; }","duration":"209.585164ms","start":"2026-05-14T14:20:34.060949Z","end":"2026-05-14T14:20:34.270534Z","steps":["trace[2033597982] 'agreement among raft nodes before linearized reading' (duration: 209.217393ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:20:34.270407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:20:33.964491Z","time spent":"305.727046ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-14T14:20:56.585331Z","caller":"traceutil/trace.go:171","msg":"trace[2143183077] transaction","detail":"{read_only:false; response_revision:1461; number_of_response:1; }","duration":"207.349063ms","start":"2026-05-14T14:20:56.377958Z","end":"2026-05-14T14:20:56.585307Z","steps":["trace[2143183077] 'process raft request' (duration: 207.138087ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:20:56.585353Z","caller":"traceutil/trace.go:171","msg":"trace[838668114] linearizableReadLoop","detail":"{readStateIndex:1510; appliedIndex:1509; }","duration":"182.692369ms","start":"2026-05-14T14:20:56.402565Z","end":"2026-05-14T14:20:56.585257Z","steps":["trace[838668114] 'read index received' (duration: 182.438621ms)","trace[838668114] 'applied index is now lower than readState.Index' (duration: 252.208µs)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:20:56.585668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.081601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-14T14:20:56.585721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.666319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-14T14:20:56.58575Z","caller":"traceutil/trace.go:171","msg":"trace[1244157218] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1461; }","duration":"148.739701ms","start":"2026-05-14T14:20:56.437002Z","end":"2026-05-14T14:20:56.585742Z","steps":["trace[1244157218] 'agreement among raft nodes before linearized reading' (duration: 148.620808ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:20:56.585755Z","caller":"traceutil/trace.go:171","msg":"trace[215433178] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1461; }","duration":"183.213525ms","start":"2026-05-14T14:20:56.40252Z","end":"2026-05-14T14:20:56.585734Z","steps":["trace[215433178] 'agreement among raft nodes before linearized reading' (duration: 182.946427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:20:56.585838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.856155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-14T14:20:56.585857Z","caller":"traceutil/trace.go:171","msg":"trace[1199323168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1461; }","duration":"148.905537ms","start":"2026-05-14T14:20:56.436947Z","end":"2026-05-14T14:20:56.585853Z","steps":["trace[1199323168] 'agreement among raft nodes before linearized reading' (duration: 148.858625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:20:56.586248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.424179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-14T14:20:56.586371Z","caller":"traceutil/trace.go:171","msg":"trace[43214603] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1461; }","duration":"153.566644ms","start":"2026-05-14T14:20:56.43279Z","end":"2026-05-14T14:20:56.586357Z","steps":["trace[43214603] 'agreement among raft nodes before linearized reading' (duration: 152.779129ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:20:58.09202Z","caller":"traceutil/trace.go:171","msg":"trace[1444087080] transaction","detail":"{read_only:false; response_revision:1472; number_of_response:1; }","duration":"123.188547ms","start":"2026-05-14T14:20:57.968808Z","end":"2026-05-14T14:20:58.091997Z","steps":["trace[1444087080] 'process raft request' (duration: 123.019241ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:21:22.826069Z","caller":"traceutil/trace.go:171","msg":"trace[648896968] linearizableReadLoop","detail":"{readStateIndex:1961; appliedIndex:1960; }","duration":"276.714823ms","start":"2026-05-14T14:21:22.549331Z","end":"2026-05-14T14:21:22.826046Z","steps":["trace[648896968] 'read index received' (duration: 276.538077ms)","trace[648896968] 'applied index is now lower than readState.Index' (duration: 175.726µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:21:22.826147Z","caller":"traceutil/trace.go:171","msg":"trace[636789190] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"848.410343ms","start":"2026-05-14T14:21:21.977701Z","end":"2026-05-14T14:21:22.826112Z","steps":["trace[636789190] 'process raft request' (duration: 848.181316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:21:22.826469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.38456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-88e1626e-46cd-495a-90b3-b7004e0cf5e4\" ","response":"range_response_count:1 size:3746"} {"level":"warn","ts":"2026-05-14T14:21:22.826496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.359749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-14T14:21:22.826605Z","caller":"traceutil/trace.go:171","msg":"trace[1818383357] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1886; }","duration":"100.492424ms","start":"2026-05-14T14:21:22.726069Z","end":"2026-05-14T14:21:22.826562Z","steps":["trace[1818383357] 'agreement among raft nodes before linearized reading' (duration: 100.218874ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:21:22.826526Z","caller":"traceutil/trace.go:171","msg":"trace[777564534] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-88e1626e-46cd-495a-90b3-b7004e0cf5e4; range_end:; response_count:1; response_revision:1886; }","duration":"100.484853ms","start":"2026-05-14T14:21:22.726026Z","end":"2026-05-14T14:21:22.82651Z","steps":["trace[777564534] 'agreement among raft nodes before linearized reading' (duration: 100.321597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:21:22.827135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.856775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-14T14:21:22.827183Z","caller":"traceutil/trace.go:171","msg":"trace[1924142420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1886; }","duration":"102.906226ms","start":"2026-05-14T14:21:22.724265Z","end":"2026-05-14T14:21:22.827171Z","steps":["trace[1924142420] 'agreement among raft nodes before linearized reading' (duration: 101.967404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:21:22.827237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:21:21.977679Z","time spent":"848.546397ms","remote":"127.0.0.1:36486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T14:21:22.828283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.94137ms","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-05-14T14:21:22.828344Z","caller":"traceutil/trace.go:171","msg":"trace[1853759483] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1886; }","duration":"279.047873ms","start":"2026-05-14T14:21:22.549282Z","end":"2026-05-14T14:21:22.828329Z","steps":["trace[1853759483] 'agreement among raft nodes before linearized reading' (duration: 276.91644ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:21:22.966654Z","caller":"traceutil/trace.go:171","msg":"trace[1785125149] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"134.018304ms","start":"2026-05-14T14:21:22.832614Z","end":"2026-05-14T14:21:22.966632Z","steps":["trace[1785125149] 'process raft request' (duration: 133.939371ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:21:22.966646Z","caller":"traceutil/trace.go:171","msg":"trace[2035327272] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"134.012924ms","start":"2026-05-14T14:21:22.832588Z","end":"2026-05-14T14:21:22.966601Z","steps":["trace[2035327272] 'process raft request' (duration: 133.812716ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:21:22.967229Z","caller":"traceutil/trace.go:171","msg":"trace[742365353] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"134.575913ms","start":"2026-05-14T14:21:22.832632Z","end":"2026-05-14T14:21:22.967208Z","steps":["trace[742365353] 'process raft request' (duration: 133.969992ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:21:22.967373Z","caller":"traceutil/trace.go:171","msg":"trace[1210127926] linearizableReadLoop","detail":"{readStateIndex:1966; appliedIndex:1961; }","duration":"103.57694ms","start":"2026-05-14T14:21:22.863743Z","end":"2026-05-14T14:21:22.967319Z","steps":["trace[1210127926] 'read index received' (duration: 44.099402ms)","trace[1210127926] 'applied index is now lower than readState.Index' (duration: 59.476408ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:21:22.967422Z","caller":"traceutil/trace.go:171","msg":"trace[2075398465] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"134.723789ms","start":"2026-05-14T14:21:22.832688Z","end":"2026-05-14T14:21:22.967412Z","steps":["trace[2075398465] 'process raft request' (duration: 134.412507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:21:22.967645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.86793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18af74820925848f\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-05-14T14:21:22.967722Z","caller":"traceutil/trace.go:171","msg":"trace[1370998511] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18af74820925848f; range_end:; response_count:1; response_revision:1891; }","duration":"103.992484ms","start":"2026-05-14T14:21:22.863709Z","end":"2026-05-14T14:21:22.967701Z","steps":["trace[1370998511] 'agreement among raft nodes before linearized reading' (duration: 103.779257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:21:23.629343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.481377ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T14:21:23.629667Z","caller":"traceutil/trace.go:171","msg":"trace[1789531782] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"549.4342ms","start":"2026-05-14T14:21:23.080201Z","end":"2026-05-14T14:21:23.629635Z","steps":["trace[1789531782] 'process raft request' (duration: 321.560369ms)","trace[1789531782] 'compare' (duration: 227.094454ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:21:23.629786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:21:23.080176Z","time spent":"549.555484ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T14:21:23.629774Z","caller":"traceutil/trace.go:171","msg":"trace[959073341] linearizableReadLoop","detail":"{readStateIndex:1969; appliedIndex:1968; }","duration":"515.459172ms","start":"2026-05-14T14:21:23.114274Z","end":"2026-05-14T14:21:23.629733Z","steps":["trace[959073341] 'read index received' (duration: 287.515089ms)","trace[959073341] 'applied index is now lower than readState.Index' (duration: 227.941863ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:21:23.629904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.600367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:21:23.630006Z","caller":"traceutil/trace.go:171","msg":"trace[1622870654] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1894; }","duration":"515.736161ms","start":"2026-05-14T14:21:23.114247Z","end":"2026-05-14T14:21:23.629983Z","steps":["trace[1622870654] 'agreement among raft nodes before linearized reading' (duration: 515.566336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:21:23.63006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:21:23.114235Z","time spent":"515.812383ms","remote":"127.0.0.1:36278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T14:21:23.630304Z","caller":"traceutil/trace.go:171","msg":"trace[1081865955] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"213.247348ms","start":"2026-05-14T14:21:23.417039Z","end":"2026-05-14T14:21:23.630287Z","steps":["trace[1081865955] 'process raft request' (duration: 212.426309ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:21:23.630364Z","caller":"traceutil/trace.go:171","msg":"trace[1342286358] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"107.168022ms","start":"2026-05-14T14:21:23.523178Z","end":"2026-05-14T14:21:23.630346Z","steps":["trace[1342286358] 'process raft request' (duration: 107.018486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:21:23.630871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.908818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-14T14:21:23.630934Z","caller":"traceutil/trace.go:171","msg":"trace[1447295401] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:1896; }","duration":"280.024992ms","start":"2026-05-14T14:21:23.350897Z","end":"2026-05-14T14:21:23.630922Z","steps":["trace[1447295401] 'agreement among raft nodes before linearized reading' (duration: 279.576946ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:21:35.191711Z","caller":"traceutil/trace.go:171","msg":"trace[461522717] transaction","detail":"{read_only:false; response_revision:1993; number_of_response:1; }","duration":"116.259547ms","start":"2026-05-14T14:21:35.075405Z","end":"2026-05-14T14:21:35.191665Z","steps":["trace[461522717] 'process raft request' (duration: 88.185645ms)","trace[461522717] 'compare' (duration: 27.887925ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:21:35.224147Z","caller":"traceutil/trace.go:171","msg":"trace[1737438761] transaction","detail":"{read_only:false; response_revision:1995; number_of_response:1; }","duration":"147.589703ms","start":"2026-05-14T14:21:35.076534Z","end":"2026-05-14T14:21:35.224124Z","steps":["trace[1737438761] 'process raft request' (duration: 147.521321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:21:35.224551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.457086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:21:35.224605Z","caller":"traceutil/trace.go:171","msg":"trace[540659564] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1996; }","duration":"109.56192ms","start":"2026-05-14T14:21:35.115029Z","end":"2026-05-14T14:21:35.224591Z","steps":["trace[540659564] 'agreement among raft nodes before linearized reading' (duration: 109.451595ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:21:35.224786Z","caller":"traceutil/trace.go:171","msg":"trace[1467053443] transaction","detail":"{read_only:false; response_revision:1994; number_of_response:1; }","duration":"148.683811ms","start":"2026-05-14T14:21:35.075517Z","end":"2026-05-14T14:21:35.224201Z","steps":["trace[1467053443] 'process raft request' (duration: 148.428852ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:21:35.224417Z","caller":"traceutil/trace.go:171","msg":"trace[182756913] linearizableReadLoop","detail":"{readStateIndex:2074; appliedIndex:2071; }","duration":"109.285541ms","start":"2026-05-14T14:21:35.115077Z","end":"2026-05-14T14:21:35.224363Z","steps":["trace[182756913] 'read index received' (duration: 48.528094ms)","trace[182756913] 'applied index is now lower than readState.Index' (duration: 60.754746ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:21:35.855079Z","caller":"traceutil/trace.go:171","msg":"trace[629914341] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"153.133089ms","start":"2026-05-14T14:21:35.701913Z","end":"2026-05-14T14:21:35.855046Z","steps":["trace[629914341] 'process raft request' (duration: 123.914137ms)","trace[629914341] 'compare' (duration: 29.040346ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:21:35.855339Z","caller":"traceutil/trace.go:171","msg":"trace[408132567] transaction","detail":"{read_only:false; response_revision:1999; number_of_response:1; }","duration":"149.987357ms","start":"2026-05-14T14:21:35.705297Z","end":"2026-05-14T14:21:35.855284Z","steps":["trace[408132567] 'process raft request' (duration: 149.714018ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:21:38.759526Z","caller":"traceutil/trace.go:171","msg":"trace[865600756] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"197.201728ms","start":"2026-05-14T14:21:38.562307Z","end":"2026-05-14T14:21:38.759509Z","steps":["trace[865600756] 'process raft request' (duration: 197.067422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:21:39.738181Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219825085517617,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T14:21:40.239375Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219825085517617,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T14:21:40.270432Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.035062539s","expected-duration":"1s"} {"level":"info","ts":"2026-05-14T14:21:40.276487Z","caller":"traceutil/trace.go:171","msg":"trace[187448876] transaction","detail":"{read_only:false; response_revision:2026; number_of_response:1; }","duration":"1.041225868s","start":"2026-05-14T14:21:39.235235Z","end":"2026-05-14T14:21:40.276461Z","steps":["trace[187448876] 'process raft request' (duration: 1.035333988s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:21:40.276876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:21:39.23521Z","time spent":"1.041340703s","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T14:21:40.283684Z","caller":"traceutil/trace.go:171","msg":"trace[1919919900] linearizableReadLoop","detail":"{readStateIndex:2110; appliedIndex:2105; }","duration":"1.045691118s","start":"2026-05-14T14:21:39.237976Z","end":"2026-05-14T14:21:40.283667Z","steps":["trace[1919919900] 'read index received' (duration: 1.032604981s)","trace[1919919900] 'applied index is now lower than readState.Index' (duration: 13.085207ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:21:40.2838Z","caller":"traceutil/trace.go:171","msg":"trace[2053961180] transaction","detail":"{read_only:false; response_revision:2028; number_of_response:1; }","duration":"558.891467ms","start":"2026-05-14T14:21:39.724898Z","end":"2026-05-14T14:21:40.28379Z","steps":["trace[2053961180] 'process raft request' (duration: 558.658509ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:21:40.283882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:21:39.724873Z","time spent":"558.960188ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T14:21:40.283978Z","caller":"traceutil/trace.go:171","msg":"trace[2136934091] transaction","detail":"{read_only:false; response_revision:2029; number_of_response:1; }","duration":"416.095183ms","start":"2026-05-14T14:21:39.867863Z","end":"2026-05-14T14:21:40.283958Z","steps":["trace[2136934091] 'process raft request' (duration: 415.7384ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:21:40.284013Z","caller":"traceutil/trace.go:171","msg":"trace[1980508590] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"414.089411ms","start":"2026-05-14T14:21:39.869896Z","end":"2026-05-14T14:21:40.283986Z","steps":["trace[1980508590] 'process raft request' (duration: 413.736398ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:21:40.284003Z","caller":"traceutil/trace.go:171","msg":"trace[202435503] transaction","detail":"{read_only:false; response_revision:2027; number_of_response:1; }","duration":"988.807713ms","start":"2026-05-14T14:21:39.29515Z","end":"2026-05-14T14:21:40.283957Z","steps":["trace[202435503] 'process raft request' (duration: 988.303984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:21:40.284087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:21:39.867824Z","time spent":"416.198036ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T14:21:40.284024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.04604333s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-14T14:21:40.284141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:21:39.869882Z","time spent":"414.193995ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T14:21:40.284162Z","caller":"traceutil/trace.go:171","msg":"trace[1707227015] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2030; }","duration":"1.046215146s","start":"2026-05-14T14:21:39.237937Z","end":"2026-05-14T14:21:40.284152Z","steps":["trace[1707227015] 'agreement among raft nodes before linearized reading' (duration: 1.045959728s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:21:40.284188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:21:39.237917Z","time spent":"1.046264597s","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-14T14:21:40.284195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:21:39.295126Z","time spent":"988.974048ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8111,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T14:21:40.284572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.832087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:21:40.284621Z","caller":"traceutil/trace.go:171","msg":"trace[379030990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2030; }","duration":"167.91546ms","start":"2026-05-14T14:21:40.116694Z","end":"2026-05-14T14:21:40.284609Z","steps":["trace[379030990] 'agreement among raft nodes before linearized reading' (duration: 167.843428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:21:40.284649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.684183ms","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-05-14T14:21:40.284681Z","caller":"traceutil/trace.go:171","msg":"trace[1463469245] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2030; }","duration":"391.772486ms","start":"2026-05-14T14:21:39.892897Z","end":"2026-05-14T14:21:40.284669Z","steps":["trace[1463469245] 'agreement among raft nodes before linearized reading' (duration: 391.672633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:21:40.284682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.905742ms","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-05-14T14:21:40.284702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:21:39.892866Z","time spent":"391.830408ms","remote":"127.0.0.1:48462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-14T14:21:40.284729Z","caller":"traceutil/trace.go:171","msg":"trace[2142047055] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2030; }","duration":"319.023667ms","start":"2026-05-14T14:21:39.965691Z","end":"2026-05-14T14:21:40.284714Z","steps":["trace[2142047055] 'agreement among raft nodes before linearized reading' (duration: 318.82524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:21:40.28478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:21:39.965643Z","time spent":"319.128591ms","remote":"127.0.0.1:48462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-14T14:21:40.284935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.046156095s","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-05-14T14:21:40.284959Z","caller":"traceutil/trace.go:171","msg":"trace[1490024664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2030; }","duration":"1.046199077s","start":"2026-05-14T14:21:39.238753Z","end":"2026-05-14T14:21:40.284952Z","steps":["trace[1490024664] 'agreement among raft nodes before linearized reading' (duration: 1.04574508s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:21:40.284978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:21:39.238743Z","time spent":"1.046230247s","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-14T14:22:04.082022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.298817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T14:22:04.082084Z","caller":"traceutil/trace.go:171","msg":"trace[834707249] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:2160; }","duration":"480.373921ms","start":"2026-05-14T14:22:03.601697Z","end":"2026-05-14T14:22:04.082071Z","steps":["trace[834707249] 'count revisions from in-memory index tree' (duration: 480.217345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:22:04.082115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:22:03.601643Z","time spent":"480.463384ms","remote":"127.0.0.1:48274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":2,"response size":29,"request content":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true "} {"level":"info","ts":"2026-05-14T14:22:04.579486Z","caller":"traceutil/trace.go:171","msg":"trace[1258684884] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"193.809453ms","start":"2026-05-14T14:22:04.385648Z","end":"2026-05-14T14:22:04.579457Z","steps":["trace[1258684884] 'process raft request' (duration: 193.639817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:22:04.958848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.19842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T14:22:04.959181Z","caller":"traceutil/trace.go:171","msg":"trace[1093019848] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"529.838269ms","start":"2026-05-14T14:22:04.429302Z","end":"2026-05-14T14:22:04.95914Z","steps":["trace[1093019848] 'process raft request' (duration: 162.254065ms)","trace[1093019848] 'compare' (duration: 366.804296ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:22:04.959229Z","caller":"traceutil/trace.go:171","msg":"trace[1098707656] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"529.540029ms","start":"2026-05-14T14:22:04.429669Z","end":"2026-05-14T14:22:04.959209Z","steps":["trace[1098707656] 'process raft request' (duration: 529.363423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:22:04.959421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:22:04.429654Z","time spent":"529.722806ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T14:22:04.959395Z","caller":"traceutil/trace.go:171","msg":"trace[1934178008] linearizableReadLoop","detail":"{readStateIndex:2249; appliedIndex:2245; }","duration":"508.423448ms","start":"2026-05-14T14:22:04.450938Z","end":"2026-05-14T14:22:04.959361Z","steps":["trace[1934178008] 'read index received' (duration: 128.393563ms)","trace[1934178008] 'applied index is now lower than readState.Index' (duration: 380.027325ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:22:04.959439Z","caller":"traceutil/trace.go:171","msg":"trace[791157653] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"529.716436ms","start":"2026-05-14T14:22:04.4297Z","end":"2026-05-14T14:22:04.959416Z","steps":["trace[791157653] 'process raft request' (duration: 529.470566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:22:04.95958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.630215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-14T14:22:04.959646Z","caller":"traceutil/trace.go:171","msg":"trace[587804731] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2164; }","duration":"508.735559ms","start":"2026-05-14T14:22:04.450898Z","end":"2026-05-14T14:22:04.959634Z","steps":["trace[587804731] 'agreement among raft nodes before linearized reading' (duration: 508.578153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:22:04.959629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:22:04.429682Z","time spent":"529.858171ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T14:22:04.959678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:22:04.450886Z","time spent":"508.783071ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-14T14:22:04.959702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:22:04.42928Z","time spent":"530.231165ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T14:22:04.959878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.014243ms","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-05-14T14:22:04.959942Z","caller":"traceutil/trace.go:171","msg":"trace[2000064936] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2164; }","duration":"494.103216ms","start":"2026-05-14T14:22:04.465821Z","end":"2026-05-14T14:22:04.959924Z","steps":["trace[2000064936] 'agreement among raft nodes before linearized reading' (duration: 493.94089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:22:04.959993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:22:04.465792Z","time spent":"494.18873ms","remote":"127.0.0.1:48462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-14T14:22:04.960123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.048996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-14T14:22:04.960476Z","caller":"traceutil/trace.go:171","msg":"trace[470609179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2164; }","duration":"504.531542ms","start":"2026-05-14T14:22:04.455931Z","end":"2026-05-14T14:22:04.960463Z","steps":["trace[470609179] 'agreement among raft nodes before linearized reading' (duration: 503.830006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:22:04.960529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:22:04.45591Z","time spent":"504.592185ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-14T14:22:53.683946Z","caller":"traceutil/trace.go:171","msg":"trace[1840994571] transaction","detail":"{read_only:false; response_revision:2528; number_of_response:1; }","duration":"547.967022ms","start":"2026-05-14T14:22:53.135952Z","end":"2026-05-14T14:22:53.683919Z","steps":["trace[1840994571] 'process raft request' (duration: 547.786315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:22:53.684171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:22:53.135931Z","time spent":"548.121757ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T14:22:53.693846Z","caller":"traceutil/trace.go:171","msg":"trace[1127475246] linearizableReadLoop","detail":"{readStateIndex:2626; appliedIndex:2623; }","duration":"500.206478ms","start":"2026-05-14T14:22:53.193626Z","end":"2026-05-14T14:22:53.693833Z","steps":["trace[1127475246] 'read index received' (duration: 490.083098ms)","trace[1127475246] 'applied index is now lower than readState.Index' (duration: 10.12266ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:22:53.693911Z","caller":"traceutil/trace.go:171","msg":"trace[328100217] transaction","detail":"{read_only:false; response_revision:2529; number_of_response:1; }","duration":"554.472913ms","start":"2026-05-14T14:22:53.139402Z","end":"2026-05-14T14:22:53.693875Z","steps":["trace[328100217] 'process raft request' (duration: 554.332768ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:22:53.693956Z","caller":"traceutil/trace.go:171","msg":"trace[1782769802] transaction","detail":"{read_only:false; response_revision:2530; number_of_response:1; }","duration":"554.482223ms","start":"2026-05-14T14:22:53.139445Z","end":"2026-05-14T14:22:53.693928Z","steps":["trace[1782769802] 'process raft request' (duration: 554.360088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:22:53.694033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.405765ms","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-05-14T14:22:53.694061Z","caller":"traceutil/trace.go:171","msg":"trace[949058343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2530; }","duration":"500.482239ms","start":"2026-05-14T14:22:53.193571Z","end":"2026-05-14T14:22:53.694054Z","steps":["trace[949058343] 'agreement among raft nodes before linearized reading' (duration: 500.346413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:22:53.694056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:22:53.139381Z","time spent":"554.599747ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T14:22:53.694082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:22:53.193553Z","time spent":"500.524401ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-14T14:22:53.694108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:22:53.139421Z","time spent":"554.601287ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T14:22:53.6943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.900591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-14T14:22:53.694372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.272798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-14T14:22:53.694354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.10456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-14T14:22:53.694382Z","caller":"traceutil/trace.go:171","msg":"trace[1938328766] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2530; }","duration":"194.015435ms","start":"2026-05-14T14:22:53.500354Z","end":"2026-05-14T14:22:53.694369Z","steps":["trace[1938328766] 'agreement among raft nodes before linearized reading' (duration: 193.843149ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:22:53.694396Z","caller":"traceutil/trace.go:171","msg":"trace[373422233] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:2530; }","duration":"340.33707ms","start":"2026-05-14T14:22:53.354052Z","end":"2026-05-14T14:22:53.694389Z","steps":["trace[373422233] 'agreement among raft nodes before linearized reading' (duration: 340.282838ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:22:53.694415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:22:53.354031Z","time spent":"340.380102ms","remote":"127.0.0.1:36328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"info","ts":"2026-05-14T14:22:53.694417Z","caller":"traceutil/trace.go:171","msg":"trace[2055644727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2530; }","duration":"306.235745ms","start":"2026-05-14T14:22:53.388164Z","end":"2026-05-14T14:22:53.6944Z","steps":["trace[2055644727] 'agreement among raft nodes before linearized reading' (duration: 306.064869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:22:53.694454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:22:53.388142Z","time spent":"306.304338ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-14T14:22:53.694637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.092066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-14T14:22:53.694743Z","caller":"traceutil/trace.go:171","msg":"trace[2040249492] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2530; }","duration":"499.234071ms","start":"2026-05-14T14:22:53.195501Z","end":"2026-05-14T14:22:53.694735Z","steps":["trace[2040249492] 'agreement among raft nodes before linearized reading' (duration: 498.68276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:22:53.694769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:22:53.195484Z","time spent":"499.279863ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-14T14:23:26.739628Z","caller":"traceutil/trace.go:171","msg":"trace[2071755047] linearizableReadLoop","detail":"{readStateIndex:2819; appliedIndex:2818; }","duration":"302.563829ms","start":"2026-05-14T14:23:26.437032Z","end":"2026-05-14T14:23:26.739596Z","steps":["trace[2071755047] 'read index received' (duration: 302.414533ms)","trace[2071755047] 'applied index is now lower than readState.Index' (duration: 148.606µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:23:26.739699Z","caller":"traceutil/trace.go:171","msg":"trace[1868157582] transaction","detail":"{read_only:false; response_revision:2715; number_of_response:1; }","duration":"493.639955ms","start":"2026-05-14T14:23:26.246011Z","end":"2026-05-14T14:23:26.739651Z","steps":["trace[1868157582] 'process raft request' (duration: 493.437547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:23:26.739926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:23:26.245986Z","time spent":"493.825283ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":766,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T14:23:26.740254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.396721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T14:23:26.740391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.3505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:23:26.740428Z","caller":"traceutil/trace.go:171","msg":"trace[1250522566] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:2715; }","duration":"303.455894ms","start":"2026-05-14T14:23:26.436964Z","end":"2026-05-14T14:23:26.74042Z","steps":["trace[1250522566] 'agreement among raft nodes before linearized reading' (duration: 302.749776ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:23:26.740386Z","caller":"traceutil/trace.go:171","msg":"trace[16967848] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2715; }","duration":"154.603039ms","start":"2026-05-14T14:23:26.585762Z","end":"2026-05-14T14:23:26.740365Z","steps":["trace[16967848] 'agreement among raft nodes before linearized reading' (duration: 154.315408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:23:26.740456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:23:26.436939Z","time spent":"303.510396ms","remote":"127.0.0.1:36428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-05-14T14:23:27.743666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.278868ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T14:23:27.743838Z","caller":"traceutil/trace.go:171","msg":"trace[1261069692] transaction","detail":"{read_only:false; response_revision:2717; number_of_response:1; }","duration":"695.160129ms","start":"2026-05-14T14:23:27.048644Z","end":"2026-05-14T14:23:27.743804Z","steps":["trace[1261069692] 'process raft request' (duration: 223.05039ms)","trace[1261069692] 'compare' (duration: 469.901583ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:23:27.74403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:23:27.048618Z","time spent":"695.303915ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T14:23:40.124574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.682376ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T14:23:40.124671Z","caller":"traceutil/trace.go:171","msg":"trace[122687537] transaction","detail":"{read_only:false; response_revision:2781; number_of_response:1; }","duration":"250.703678ms","start":"2026-05-14T14:23:39.873954Z","end":"2026-05-14T14:23:40.124657Z","steps":["trace[122687537] 'process raft request' (duration: 94.869716ms)","trace[122687537] 'compare' (duration: 155.52128ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:23:40.155109Z","caller":"traceutil/trace.go:171","msg":"trace[196548830] linearizableReadLoop","detail":"{readStateIndex:2889; appliedIndex:2887; }","duration":"214.125881ms","start":"2026-05-14T14:23:39.940957Z","end":"2026-05-14T14:23:40.155083Z","steps":["trace[196548830] 'read index received' (duration: 27.880094ms)","trace[196548830] 'applied index is now lower than readState.Index' (duration: 186.244367ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:23:40.155435Z","caller":"traceutil/trace.go:171","msg":"trace[145196399] transaction","detail":"{read_only:false; response_revision:2782; number_of_response:1; }","duration":"233.828596ms","start":"2026-05-14T14:23:39.921593Z","end":"2026-05-14T14:23:40.155422Z","steps":["trace[145196399] 'process raft request' (duration: 233.360637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:23:40.155552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.109942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:23:40.155617Z","caller":"traceutil/trace.go:171","msg":"trace[1207791745] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2782; }","duration":"214.208306ms","start":"2026-05-14T14:23:39.941391Z","end":"2026-05-14T14:23:40.1556Z","steps":["trace[1207791745] 'agreement among raft nodes before linearized reading' (duration: 214.087901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:23:40.155702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.089365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-14T14:23:40.155785Z","caller":"traceutil/trace.go:171","msg":"trace[1839891972] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2782; }","duration":"138.20392ms","start":"2026-05-14T14:23:40.017556Z","end":"2026-05-14T14:23:40.15576Z","steps":["trace[1839891972] 'agreement among raft nodes before linearized reading' (duration: 137.984341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:23:40.155824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.809764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-14T14:23:40.155874Z","caller":"traceutil/trace.go:171","msg":"trace[1581628143] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2782; }","duration":"193.930969ms","start":"2026-05-14T14:23:39.961933Z","end":"2026-05-14T14:23:40.155864Z","steps":["trace[1581628143] 'agreement among raft nodes before linearized reading' (duration: 193.776192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:23:40.155893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.99541ms","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-05-14T14:23:40.155935Z","caller":"traceutil/trace.go:171","msg":"trace[297910390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2782; }","duration":"195.039723ms","start":"2026-05-14T14:23:39.960885Z","end":"2026-05-14T14:23:40.155925Z","steps":["trace[297910390] 'agreement among raft nodes before linearized reading' (duration: 194.927919ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:23:40.155992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.998337ms","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-05-14T14:23:40.156125Z","caller":"traceutil/trace.go:171","msg":"trace[879481597] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2782; }","duration":"215.188264ms","start":"2026-05-14T14:23:39.940911Z","end":"2026-05-14T14:23:40.156099Z","steps":["trace[879481597] 'agreement among raft nodes before linearized reading' (duration: 214.793688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:23:40.156372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.541469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-14T14:23:40.156449Z","caller":"traceutil/trace.go:171","msg":"trace[47083999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2782; }","duration":"142.673304ms","start":"2026-05-14T14:23:40.013758Z","end":"2026-05-14T14:23:40.156431Z","steps":["trace[47083999] 'agreement among raft nodes before linearized reading' (duration: 141.994537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:23:40.3363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.711669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:9349"} {"level":"info","ts":"2026-05-14T14:23:40.336381Z","caller":"traceutil/trace.go:171","msg":"trace[1360286304] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:2787; }","duration":"109.861106ms","start":"2026-05-14T14:23:40.226501Z","end":"2026-05-14T14:23:40.336362Z","steps":["trace[1360286304] 'range keys from in-memory index tree' (duration: 109.501532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:23:48.438753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.964829ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T14:23:48.439116Z","caller":"traceutil/trace.go:171","msg":"trace[1609096029] linearizableReadLoop","detail":"{readStateIndex:2935; appliedIndex:2933; }","duration":"184.163094ms","start":"2026-05-14T14:23:48.25493Z","end":"2026-05-14T14:23:48.438919Z","steps":["trace[1609096029] 'read index received' (duration: 65.757814ms)","trace[1609096029] 'applied index is now lower than readState.Index' (duration: 118.230829ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:23:48.439341Z","caller":"traceutil/trace.go:171","msg":"trace[1344136787] transaction","detail":"{read_only:false; response_revision:2827; number_of_response:1; }","duration":"195.161452ms","start":"2026-05-14T14:23:48.244167Z","end":"2026-05-14T14:23:48.439328Z","steps":["trace[1344136787] 'process raft request' (duration: 194.68837ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:23:48.439491Z","caller":"traceutil/trace.go:171","msg":"trace[657483586] transaction","detail":"{read_only:false; response_revision:2826; number_of_response:1; }","duration":"197.56116ms","start":"2026-05-14T14:23:48.241804Z","end":"2026-05-14T14:23:48.439365Z","steps":["trace[657483586] 'process raft request' (duration: 78.892529ms)","trace[657483586] 'compare' (duration: 117.699137ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:23:48.439926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.606248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T14:23:48.439965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.807383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-14T14:23:48.43999Z","caller":"traceutil/trace.go:171","msg":"trace[695881129] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:2827; }","duration":"171.68006ms","start":"2026-05-14T14:23:48.268295Z","end":"2026-05-14T14:23:48.439975Z","steps":["trace[695881129] 'agreement among raft nodes before linearized reading' (duration: 171.559738ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:23:48.440012Z","caller":"traceutil/trace.go:171","msg":"trace[912117854] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2827; }","duration":"183.908093ms","start":"2026-05-14T14:23:48.256089Z","end":"2026-05-14T14:23:48.439997Z","steps":["trace[912117854] 'agreement among raft nodes before linearized reading' (duration: 183.751001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:23:48.440223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.251978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-14T14:23:48.440387Z","caller":"traceutil/trace.go:171","msg":"trace[577117504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2827; }","duration":"185.473038ms","start":"2026-05-14T14:23:48.25489Z","end":"2026-05-14T14:23:48.440363Z","steps":["trace[577117504] 'agreement among raft nodes before linearized reading' (duration: 185.049558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:23:48.440738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.851138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-14T14:23:48.440878Z","caller":"traceutil/trace.go:171","msg":"trace[1444998410] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2827; }","duration":"177.024938ms","start":"2026-05-14T14:23:48.26383Z","end":"2026-05-14T14:23:48.440855Z","steps":["trace[1444998410] 'agreement among raft nodes before linearized reading' (duration: 176.126916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:23:48.441679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.23555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-14T14:23:48.441772Z","caller":"traceutil/trace.go:171","msg":"trace[451325429] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2827; }","duration":"184.703515ms","start":"2026-05-14T14:23:48.257046Z","end":"2026-05-14T14:23:48.441749Z","steps":["trace[451325429] 'agreement among raft nodes before linearized reading' (duration: 183.07049ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:24:20.823654Z","caller":"traceutil/trace.go:171","msg":"trace[1404631456] linearizableReadLoop","detail":"{readStateIndex:3112; appliedIndex:3111; }","duration":"125.653394ms","start":"2026-05-14T14:24:20.697973Z","end":"2026-05-14T14:24:20.823626Z","steps":["trace[1404631456] 'read index received' (duration: 88.989411ms)","trace[1404631456] 'applied index is now lower than readState.Index' (duration: 36.663083ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:24:20.823751Z","caller":"traceutil/trace.go:171","msg":"trace[761205534] transaction","detail":"{read_only:false; response_revision:2996; number_of_response:1; }","duration":"126.097118ms","start":"2026-05-14T14:24:20.697604Z","end":"2026-05-14T14:24:20.823701Z","steps":["trace[761205534] 'process raft request' (duration: 89.445036ms)","trace[761205534] 'compare' (duration: 36.44034ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:24:20.823986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.370356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-14T14:24:20.824079Z","caller":"traceutil/trace.go:171","msg":"trace[316520443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2996; }","duration":"103.503617ms","start":"2026-05-14T14:24:20.720553Z","end":"2026-05-14T14:24:20.824056Z","steps":["trace[316520443] 'agreement among raft nodes before linearized reading' (duration: 103.273185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:24:20.824151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.178233ms","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-05-14T14:24:20.824248Z","caller":"traceutil/trace.go:171","msg":"trace[1937663878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2996; }","duration":"103.351304ms","start":"2026-05-14T14:24:20.720879Z","end":"2026-05-14T14:24:20.82423Z","steps":["trace[1937663878] 'agreement among raft nodes before linearized reading' (duration: 103.055202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:24:20.824843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.874766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-14T14:24:20.824913Z","caller":"traceutil/trace.go:171","msg":"trace[1894668305] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2996; }","duration":"126.985808ms","start":"2026-05-14T14:24:20.697915Z","end":"2026-05-14T14:24:20.8249Z","steps":["trace[1894668305] 'agreement among raft nodes before linearized reading' (duration: 125.870886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:24:25.174242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.747272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T14:24:25.174341Z","caller":"traceutil/trace.go:171","msg":"trace[1952456753] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:3021; }","duration":"153.897584ms","start":"2026-05-14T14:24:25.020409Z","end":"2026-05-14T14:24:25.174307Z","steps":["trace[1952456753] 'count revisions from in-memory index tree' (duration: 153.652192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:25:10.856785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.100698ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T14:25:10.857085Z","caller":"traceutil/trace.go:171","msg":"trace[1368303535] transaction","detail":"{read_only:false; response_revision:3254; number_of_response:1; }","duration":"184.189927ms","start":"2026-05-14T14:25:10.6728Z","end":"2026-05-14T14:25:10.85699Z","steps":["trace[1368303535] 'process raft request' (duration: 52.127791ms)","trace[1368303535] 'compare' (duration: 130.18798ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:25:41.551003Z","caller":"traceutil/trace.go:171","msg":"trace[1646656279] linearizableReadLoop","detail":"{readStateIndex:3644; appliedIndex:3643; }","duration":"204.744342ms","start":"2026-05-14T14:25:41.346221Z","end":"2026-05-14T14:25:41.550965Z","steps":["trace[1646656279] 'read index received' (duration: 155.387752ms)","trace[1646656279] 'applied index is now lower than readState.Index' (duration: 49.35522ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:25:41.551059Z","caller":"traceutil/trace.go:171","msg":"trace[1209142448] transaction","detail":"{read_only:false; response_revision:3509; number_of_response:1; }","duration":"207.922233ms","start":"2026-05-14T14:25:41.343099Z","end":"2026-05-14T14:25:41.551021Z","steps":["trace[1209142448] 'process raft request' (duration: 158.599524ms)","trace[1209142448] 'compare' (duration: 48.991081ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:25:41.551429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.172371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-14T14:25:41.551476Z","caller":"traceutil/trace.go:171","msg":"trace[752057645] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3509; }","duration":"205.273154ms","start":"2026-05-14T14:25:41.346191Z","end":"2026-05-14T14:25:41.551464Z","steps":["trace[752057645] 'agreement among raft nodes before linearized reading' (duration: 204.892805ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:25:49.391513Z","caller":"traceutil/trace.go:171","msg":"trace[389886210] transaction","detail":"{read_only:false; response_revision:3550; number_of_response:1; }","duration":"159.212877ms","start":"2026-05-14T14:25:49.232261Z","end":"2026-05-14T14:25:49.391473Z","steps":["trace[389886210] 'process raft request' (duration: 159.069403ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:25:56.995077Z","caller":"traceutil/trace.go:171","msg":"trace[807615207] transaction","detail":"{read_only:false; response_revision:3589; number_of_response:1; }","duration":"176.418885ms","start":"2026-05-14T14:25:56.818624Z","end":"2026-05-14T14:25:56.995043Z","steps":["trace[807615207] 'process raft request' (duration: 176.264361ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:26:00.032351Z","caller":"traceutil/trace.go:171","msg":"trace[649020181] transaction","detail":"{read_only:false; response_revision:3605; number_of_response:1; }","duration":"149.757769ms","start":"2026-05-14T14:25:59.882569Z","end":"2026-05-14T14:26:00.032326Z","steps":["trace[649020181] 'process raft request' (duration: 95.951074ms)","trace[649020181] 'compare' (duration: 53.716083ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:26:00.120622Z","caller":"traceutil/trace.go:171","msg":"trace[472475431] transaction","detail":"{read_only:false; response_revision:3606; number_of_response:1; }","duration":"125.857618ms","start":"2026-05-14T14:25:59.994743Z","end":"2026-05-14T14:26:00.1206Z","steps":["trace[472475431] 'process raft request' (duration: 125.748995ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:26:16.87159Z","caller":"traceutil/trace.go:171","msg":"trace[193867454] transaction","detail":"{read_only:false; response_revision:3700; number_of_response:1; }","duration":"114.529659ms","start":"2026-05-14T14:26:16.757041Z","end":"2026-05-14T14:26:16.871571Z","steps":["trace[193867454] 'process raft request' (duration: 114.409266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:26:22.697205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.607861ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T14:26:22.697404Z","caller":"traceutil/trace.go:171","msg":"trace[1089869983] linearizableReadLoop","detail":"{readStateIndex:3874; appliedIndex:3873; }","duration":"369.038492ms","start":"2026-05-14T14:26:22.328349Z","end":"2026-05-14T14:26:22.697387Z","steps":["trace[1089869983] 'read index received' (duration: 181.221934ms)","trace[1089869983] 'applied index is now lower than readState.Index' (duration: 187.815268ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:26:22.697505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.144714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:26:22.697512Z","caller":"traceutil/trace.go:171","msg":"trace[516312432] transaction","detail":"{read_only:false; response_revision:3731; number_of_response:1; }","duration":"441.953057ms","start":"2026-05-14T14:26:22.255515Z","end":"2026-05-14T14:26:22.697468Z","steps":["trace[516312432] 'process raft request' (duration: 254.001216ms)","trace[516312432] 'compare' (duration: 187.477978ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:26:22.697631Z","caller":"traceutil/trace.go:171","msg":"trace[481948036] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:3731; }","duration":"369.229326ms","start":"2026-05-14T14:26:22.328344Z","end":"2026-05-14T14:26:22.697573Z","steps":["trace[481948036] 'agreement among raft nodes before linearized reading' (duration: 369.116273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:26:22.697705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:26:22.255488Z","time spent":"442.115711ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T14:26:22.697723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:26:22.328301Z","time spent":"369.39465ms","remote":"127.0.0.1:56684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"info","ts":"2026-05-14T14:26:27.938416Z","caller":"traceutil/trace.go:171","msg":"trace[1835396415] linearizableReadLoop","detail":"{readStateIndex:3966; appliedIndex:3965; }","duration":"149.868661ms","start":"2026-05-14T14:26:27.788524Z","end":"2026-05-14T14:26:27.938393Z","steps":["trace[1835396415] 'read index received' (duration: 149.268675ms)","trace[1835396415] 'applied index is now lower than readState.Index' (duration: 599.256µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:26:27.93849Z","caller":"traceutil/trace.go:171","msg":"trace[607411732] transaction","detail":"{read_only:false; response_revision:3818; number_of_response:1; }","duration":"214.39349ms","start":"2026-05-14T14:26:27.724065Z","end":"2026-05-14T14:26:27.938458Z","steps":["trace[607411732] 'process raft request' (duration: 213.932148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:26:27.938705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.158138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-14T14:26:27.938759Z","caller":"traceutil/trace.go:171","msg":"trace[358553212] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3818; }","duration":"150.252341ms","start":"2026-05-14T14:26:27.788494Z","end":"2026-05-14T14:26:27.938746Z","steps":["trace[358553212] 'agreement among raft nodes before linearized reading' (duration: 150.070636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:26:27.939033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.081927ms","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-05-14T14:26:27.93912Z","caller":"traceutil/trace.go:171","msg":"trace[1631449787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3818; }","duration":"147.202551ms","start":"2026-05-14T14:26:27.791902Z","end":"2026-05-14T14:26:27.939105Z","steps":["trace[1631449787] 'agreement among raft nodes before linearized reading' (duration: 147.008725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:26:27.939147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.282019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-14T14:26:27.939186Z","caller":"traceutil/trace.go:171","msg":"trace[192118671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3818; }","duration":"145.335551ms","start":"2026-05-14T14:26:27.793841Z","end":"2026-05-14T14:26:27.939177Z","steps":["trace[192118671] 'agreement among raft nodes before linearized reading' (duration: 145.235689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:26:28.328041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.025272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-14T14:26:28.328132Z","caller":"traceutil/trace.go:171","msg":"trace[1469255304] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3822; }","duration":"282.139366ms","start":"2026-05-14T14:26:28.045971Z","end":"2026-05-14T14:26:28.328111Z","steps":["trace[1469255304] 'range keys from in-memory index tree' (duration: 281.795006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:26:28.327931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.250026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:26:28.328258Z","caller":"traceutil/trace.go:171","msg":"trace[1119082583] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3822; }","duration":"213.636777ms","start":"2026-05-14T14:26:28.11461Z","end":"2026-05-14T14:26:28.328247Z","steps":["trace[1119082583] 'range keys from in-memory index tree' (duration: 213.123062ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:09.378685Z","caller":"traceutil/trace.go:171","msg":"trace[1104211803] linearizableReadLoop","detail":"{readStateIndex:4240; appliedIndex:4239; }","duration":"182.415967ms","start":"2026-05-14T14:27:09.196212Z","end":"2026-05-14T14:27:09.378628Z","steps":["trace[1104211803] 'read index received' (duration: 96.113497ms)","trace[1104211803] 'applied index is now lower than readState.Index' (duration: 86.3011ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:27:09.378756Z","caller":"traceutil/trace.go:171","msg":"trace[1581190661] transaction","detail":"{read_only:false; response_revision:4072; number_of_response:1; }","duration":"202.250574ms","start":"2026-05-14T14:27:09.176472Z","end":"2026-05-14T14:27:09.378722Z","steps":["trace[1581190661] 'process raft request' (duration: 115.83698ms)","trace[1581190661] 'compare' (duration: 86.037983ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:27:09.37914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.902881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-14T14:27:09.379283Z","caller":"traceutil/trace.go:171","msg":"trace[1738209549] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4072; }","duration":"183.057356ms","start":"2026-05-14T14:27:09.196164Z","end":"2026-05-14T14:27:09.379221Z","steps":["trace[1738209549] 'agreement among raft nodes before linearized reading' (duration: 182.590152ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:09.590759Z","caller":"traceutil/trace.go:171","msg":"trace[298053611] linearizableReadLoop","detail":"{readStateIndex:4241; appliedIndex:4240; }","duration":"121.751562ms","start":"2026-05-14T14:27:09.46899Z","end":"2026-05-14T14:27:09.590742Z","steps":["trace[298053611] 'read index received' (duration: 83.017655ms)","trace[298053611] 'applied index is now lower than readState.Index' (duration: 38.733227ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:27:09.591068Z","caller":"traceutil/trace.go:171","msg":"trace[1443598398] transaction","detail":"{read_only:false; response_revision:4073; number_of_response:1; }","duration":"123.968336ms","start":"2026-05-14T14:27:09.467073Z","end":"2026-05-14T14:27:09.591041Z","steps":["trace[1443598398] 'process raft request' (duration: 84.911951ms)","trace[1443598398] 'compare' (duration: 38.463759ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:27:09.591132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.119772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-14T14:27:09.591169Z","caller":"traceutil/trace.go:171","msg":"trace[446119678] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4073; }","duration":"122.200885ms","start":"2026-05-14T14:27:09.468957Z","end":"2026-05-14T14:27:09.591157Z","steps":["trace[446119678] 'agreement among raft nodes before linearized reading' (duration: 121.867935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:12.43798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.849284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-14T14:27:12.438071Z","caller":"traceutil/trace.go:171","msg":"trace[154053635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4421; }","duration":"120.993309ms","start":"2026-05-14T14:27:12.317057Z","end":"2026-05-14T14:27:12.43805Z","steps":["trace[154053635] 'range keys from in-memory index tree' (duration: 120.67997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:12.43813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.112229ms","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-05-14T14:27:12.438249Z","caller":"traceutil/trace.go:171","msg":"trace[1770219578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4421; }","duration":"112.259853ms","start":"2026-05-14T14:27:12.325963Z","end":"2026-05-14T14:27:12.438223Z","steps":["trace[1770219578] 'range keys from in-memory index tree' (duration: 111.902283ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:12.93933Z","caller":"traceutil/trace.go:171","msg":"trace[1179843190] transaction","detail":"{read_only:false; response_revision:4444; number_of_response:1; }","duration":"108.331989ms","start":"2026-05-14T14:27:12.830972Z","end":"2026-05-14T14:27:12.939304Z","steps":["trace[1179843190] 'process raft request' (duration: 108.171344ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:12.942559Z","caller":"traceutil/trace.go:171","msg":"trace[372541473] transaction","detail":"{read_only:false; response_revision:4446; number_of_response:1; }","duration":"109.809622ms","start":"2026-05-14T14:27:12.832732Z","end":"2026-05-14T14:27:12.942541Z","steps":["trace[372541473] 'process raft request' (duration: 109.774941ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:12.942804Z","caller":"traceutil/trace.go:171","msg":"trace[640489769] transaction","detail":"{read_only:false; response_revision:4445; number_of_response:1; }","duration":"111.028728ms","start":"2026-05-14T14:27:12.831738Z","end":"2026-05-14T14:27:12.942766Z","steps":["trace[640489769] 'process raft request' (duration: 110.696778ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:13.842441Z","caller":"traceutil/trace.go:171","msg":"trace[490975293] transaction","detail":"{read_only:false; response_revision:4500; number_of_response:1; }","duration":"124.494587ms","start":"2026-05-14T14:27:13.717894Z","end":"2026-05-14T14:27:13.842389Z","steps":["trace[490975293] 'process raft request' (duration: 124.25101ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:14.538152Z","caller":"traceutil/trace.go:171","msg":"trace[2129431566] linearizableReadLoop","detail":"{readStateIndex:4684; appliedIndex:4683; }","duration":"185.146024ms","start":"2026-05-14T14:27:14.352972Z","end":"2026-05-14T14:27:14.538118Z","steps":["trace[2129431566] 'read index received' (duration: 184.961107ms)","trace[2129431566] 'applied index is now lower than readState.Index' (duration: 183.017µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:27:14.538268Z","caller":"traceutil/trace.go:171","msg":"trace[1524726827] transaction","detail":"{read_only:false; response_revision:4509; number_of_response:1; }","duration":"263.590165ms","start":"2026-05-14T14:27:14.274592Z","end":"2026-05-14T14:27:14.538182Z","steps":["trace[1524726827] 'process raft request' (duration: 263.360248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:14.538944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.953048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e9f11869-7e4b-4752-8724-4d5cba3c883a\" ","response":"range_response_count:1 size:3759"} {"level":"info","ts":"2026-05-14T14:27:14.538994Z","caller":"traceutil/trace.go:171","msg":"trace[1729795629] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e9f11869-7e4b-4752-8724-4d5cba3c883a; range_end:; response_count:1; response_revision:4509; }","duration":"186.04172ms","start":"2026-05-14T14:27:14.352937Z","end":"2026-05-14T14:27:14.538979Z","steps":["trace[1729795629] 'agreement among raft nodes before linearized reading' (duration: 185.334919ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:17.293786Z","caller":"traceutil/trace.go:171","msg":"trace[1611164909] transaction","detail":"{read_only:false; response_revision:4556; number_of_response:1; }","duration":"197.094493ms","start":"2026-05-14T14:27:17.096647Z","end":"2026-05-14T14:27:17.293741Z","steps":["trace[1611164909] 'process raft request' (duration: 157.98272ms)","trace[1611164909] 'compare' (duration: 38.929497ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:27:17.293833Z","caller":"traceutil/trace.go:171","msg":"trace[459260668] linearizableReadLoop","detail":"{readStateIndex:4731; appliedIndex:4729; }","duration":"179.209776ms","start":"2026-05-14T14:27:17.114597Z","end":"2026-05-14T14:27:17.293807Z","steps":["trace[459260668] 'read index received' (duration: 38.928338ms)","trace[459260668] 'applied index is now lower than readState.Index' (duration: 140.276068ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:27:17.293964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.34825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:27:17.293989Z","caller":"traceutil/trace.go:171","msg":"trace[145669000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4556; }","duration":"179.470753ms","start":"2026-05-14T14:27:17.114512Z","end":"2026-05-14T14:27:17.293983Z","steps":["trace[145669000] 'agreement among raft nodes before linearized reading' (duration: 179.383221ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:17.29407Z","caller":"traceutil/trace.go:171","msg":"trace[1041004695] transaction","detail":"{read_only:false; response_revision:4558; number_of_response:1; }","duration":"133.606191ms","start":"2026-05-14T14:27:17.160443Z","end":"2026-05-14T14:27:17.294049Z","steps":["trace[1041004695] 'process raft request' (duration: 133.56427ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:17.294182Z","caller":"traceutil/trace.go:171","msg":"trace[1751334562] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4557; }","duration":"138.484113ms","start":"2026-05-14T14:27:17.155689Z","end":"2026-05-14T14:27:17.294173Z","steps":["trace[1751334562] 'process raft request' (duration: 138.241697ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:18.219422Z","caller":"traceutil/trace.go:171","msg":"trace[289295563] linearizableReadLoop","detail":"{readStateIndex:4749; appliedIndex:4748; }","duration":"104.371363ms","start":"2026-05-14T14:27:18.115029Z","end":"2026-05-14T14:27:18.219401Z","steps":["trace[289295563] 'read index received' (duration: 104.125516ms)","trace[289295563] 'applied index is now lower than readState.Index' (duration: 244.967µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:27:18.219475Z","caller":"traceutil/trace.go:171","msg":"trace[1368732255] transaction","detail":"{read_only:false; response_revision:4574; number_of_response:1; }","duration":"231.479229ms","start":"2026-05-14T14:27:17.98797Z","end":"2026-05-14T14:27:18.219449Z","steps":["trace[1368732255] 'process raft request' (duration: 231.247072ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:18.219569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.532238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:27:18.219606Z","caller":"traceutil/trace.go:171","msg":"trace[917161355] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4574; }","duration":"104.620651ms","start":"2026-05-14T14:27:18.114974Z","end":"2026-05-14T14:27:18.219594Z","steps":["trace[917161355] 'agreement among raft nodes before linearized reading' (duration: 104.544579ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:21.515614Z","caller":"traceutil/trace.go:171","msg":"trace[37553610] transaction","detail":"{read_only:false; response_revision:4638; number_of_response:1; }","duration":"203.258234ms","start":"2026-05-14T14:27:21.312331Z","end":"2026-05-14T14:27:21.515589Z","steps":["trace[37553610] 'process raft request' (duration: 203.080689ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:21.548229Z","caller":"traceutil/trace.go:171","msg":"trace[697340551] transaction","detail":"{read_only:false; response_revision:4639; number_of_response:1; }","duration":"105.632976ms","start":"2026-05-14T14:27:21.442566Z","end":"2026-05-14T14:27:21.548199Z","steps":["trace[697340551] 'process raft request' (duration: 105.466961ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:21.870585Z","caller":"traceutil/trace.go:171","msg":"trace[1618788037] transaction","detail":"{read_only:false; response_revision:4640; number_of_response:1; }","duration":"243.412726ms","start":"2026-05-14T14:27:21.627149Z","end":"2026-05-14T14:27:21.870562Z","steps":["trace[1618788037] 'process raft request' (duration: 243.271642ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:21.916175Z","caller":"traceutil/trace.go:171","msg":"trace[1590189582] linearizableReadLoop","detail":"{readStateIndex:4820; appliedIndex:4818; }","duration":"229.985847ms","start":"2026-05-14T14:27:21.686167Z","end":"2026-05-14T14:27:21.916153Z","steps":["trace[1590189582] 'read index received' (duration: 184.229489ms)","trace[1590189582] 'applied index is now lower than readState.Index' (duration: 45.754978ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:27:21.916362Z","caller":"traceutil/trace.go:171","msg":"trace[2060257697] transaction","detail":"{read_only:false; response_revision:4641; number_of_response:1; }","duration":"289.196527ms","start":"2026-05-14T14:27:21.627154Z","end":"2026-05-14T14:27:21.916351Z","steps":["trace[2060257697] 'process raft request' (duration: 270.250163ms)","trace[2060257697] 'compare' (duration: 18.566782ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:27:21.91648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.293387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:27:21.916523Z","caller":"traceutil/trace.go:171","msg":"trace[884818413] transaction","detail":"{read_only:false; response_revision:4642; number_of_response:1; }","duration":"201.857203ms","start":"2026-05-14T14:27:21.714631Z","end":"2026-05-14T14:27:21.916488Z","steps":["trace[884818413] 'process raft request' (duration: 201.43567ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:21.91652Z","caller":"traceutil/trace.go:171","msg":"trace[1221102640] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:4642; }","duration":"230.37843ms","start":"2026-05-14T14:27:21.686129Z","end":"2026-05-14T14:27:21.916508Z","steps":["trace[1221102640] 'agreement among raft nodes before linearized reading' (duration: 230.301158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:21.91691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.09761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-jvqfp\" ","response":"range_response_count:1 size:9670"} {"level":"info","ts":"2026-05-14T14:27:21.916956Z","caller":"traceutil/trace.go:171","msg":"trace[15815272] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-jvqfp; range_end:; response_count:1; response_revision:4642; }","duration":"203.182662ms","start":"2026-05-14T14:27:21.713763Z","end":"2026-05-14T14:27:21.916945Z","steps":["trace[15815272] 'agreement among raft nodes before linearized reading' (duration: 202.631167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:27.53117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.927233ms","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":"warn","ts":"2026-05-14T14:27:27.531235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.468385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:27:27.531285Z","caller":"traceutil/trace.go:171","msg":"trace[1386753860] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:4738; }","duration":"471.102198ms","start":"2026-05-14T14:27:27.060161Z","end":"2026-05-14T14:27:27.531263Z","steps":["trace[1386753860] 'count revisions from in-memory index tree' (duration: 470.737216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:27.531371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.617105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:27:27.531451Z","caller":"traceutil/trace.go:171","msg":"trace[373541611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4738; }","duration":"414.742988ms","start":"2026-05-14T14:27:27.11669Z","end":"2026-05-14T14:27:27.531433Z","steps":["trace[373541611] 'range keys from in-memory index tree' (duration: 414.349146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:27.53149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:27.116669Z","time spent":"414.81285ms","remote":"127.0.0.1:36278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T14:27:27.531333Z","caller":"traceutil/trace.go:171","msg":"trace[1164303146] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:4738; }","duration":"261.590789ms","start":"2026-05-14T14:27:27.269718Z","end":"2026-05-14T14:27:27.531308Z","steps":["trace[1164303146] 'count revisions from in-memory index tree' (duration: 261.386614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:27.531691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:27.060142Z","time spent":"471.183579ms","remote":"127.0.0.1:36720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-14T14:27:27.532443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.817564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e9415ac2-e3d7-457a-b913-a55bb974c66f\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-05-14T14:27:27.53251Z","caller":"traceutil/trace.go:171","msg":"trace[880942637] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e9415ac2-e3d7-457a-b913-a55bb974c66f; range_end:; response_count:1; response_revision:4738; }","duration":"560.924147ms","start":"2026-05-14T14:27:26.97157Z","end":"2026-05-14T14:27:27.532495Z","steps":["trace[880942637] 'range keys from in-memory index tree' (duration: 559.918227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:27.532777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:26.971549Z","time spent":"561.212496ms","remote":"127.0.0.1:36486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3667,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e9415ac2-e3d7-457a-b913-a55bb974c66f\" "} {"level":"info","ts":"2026-05-14T14:27:28.04153Z","caller":"traceutil/trace.go:171","msg":"trace[2014313018] transaction","detail":"{read_only:false; response_revision:4739; number_of_response:1; }","duration":"506.334856ms","start":"2026-05-14T14:27:27.535173Z","end":"2026-05-14T14:27:28.041508Z","steps":["trace[2014313018] 'process raft request' (duration: 506.118039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:28.04167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:27.535148Z","time spent":"506.450738ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T14:27:28.045821Z","caller":"traceutil/trace.go:171","msg":"trace[1868421510] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"144.337257ms","start":"2026-05-14T14:27:27.901458Z","end":"2026-05-14T14:27:28.045796Z","steps":["trace[1868421510] 'process raft request' (duration: 144.224443ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:28.04583Z","caller":"traceutil/trace.go:171","msg":"trace[403149508] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"481.410808ms","start":"2026-05-14T14:27:27.564385Z","end":"2026-05-14T14:27:28.045795Z","steps":["trace[403149508] 'process raft request' (duration: 481.187351ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:28.045931Z","caller":"traceutil/trace.go:171","msg":"trace[963035881] linearizableReadLoop","detail":"{readStateIndex:4921; appliedIndex:4919; }","duration":"270.358013ms","start":"2026-05-14T14:27:27.775559Z","end":"2026-05-14T14:27:28.045917Z","steps":["trace[963035881] 'read index received' (duration: 265.787425ms)","trace[963035881] 'applied index is now lower than readState.Index' (duration: 4.569678ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:27:28.045995Z","caller":"traceutil/trace.go:171","msg":"trace[811360705] transaction","detail":"{read_only:false; response_revision:4742; number_of_response:1; }","duration":"111.241476ms","start":"2026-05-14T14:27:27.934735Z","end":"2026-05-14T14:27:28.045977Z","steps":["trace[811360705] 'process raft request' (duration: 110.989308ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:28.046017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:27.564363Z","time spent":"481.556873ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T14:27:28.046182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.60923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e9415ac2-e3d7-457a-b913-a55bb974c66f\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-05-14T14:27:28.046247Z","caller":"traceutil/trace.go:171","msg":"trace[2054066076] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e9415ac2-e3d7-457a-b913-a55bb974c66f; range_end:; response_count:1; response_revision:4742; }","duration":"270.704423ms","start":"2026-05-14T14:27:27.775524Z","end":"2026-05-14T14:27:28.046228Z","steps":["trace[2054066076] 'agreement among raft nodes before linearized reading' (duration: 270.476096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:28.04629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.435296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-5856b9d9-t6kdx.18af74d7805a58aa\" ","response":"range_response_count:1 size:892"} {"level":"info","ts":"2026-05-14T14:27:28.04634Z","caller":"traceutil/trace.go:171","msg":"trace[1761530865] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-5856b9d9-t6kdx.18af74d7805a58aa; range_end:; response_count:1; response_revision:4742; }","duration":"269.482757ms","start":"2026-05-14T14:27:27.77683Z","end":"2026-05-14T14:27:28.046312Z","steps":["trace[1761530865] 'agreement among raft nodes before linearized reading' (duration: 269.212739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:29.004006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.224171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-14T14:27:29.004074Z","caller":"traceutil/trace.go:171","msg":"trace[265061737] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4747; }","duration":"357.346885ms","start":"2026-05-14T14:27:28.646712Z","end":"2026-05-14T14:27:29.004058Z","steps":["trace[265061737] 'range keys from in-memory index tree' (duration: 357.042816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:29.004105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:28.646688Z","time spent":"357.410217ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-14T14:27:29.004392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.248612ms","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-05-14T14:27:29.00443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.317115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e9415ac2-e3d7-457a-b913-a55bb974c66f\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-05-14T14:27:29.004488Z","caller":"traceutil/trace.go:171","msg":"trace[646384103] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e9415ac2-e3d7-457a-b913-a55bb974c66f; range_end:; response_count:1; response_revision:4747; }","duration":"464.405477ms","start":"2026-05-14T14:27:28.540066Z","end":"2026-05-14T14:27:29.004472Z","steps":["trace[646384103] 'range keys from in-memory index tree' (duration: 464.078778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:29.004519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.9084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-14T14:27:29.004558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.569811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T14:27:29.004578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.798146ms","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-05-14T14:27:29.004618Z","caller":"traceutil/trace.go:171","msg":"trace[1559271867] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4747; }","duration":"222.835457ms","start":"2026-05-14T14:27:28.781772Z","end":"2026-05-14T14:27:29.004608Z","steps":["trace[1559271867] 'range keys from in-memory index tree' (duration: 222.672133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:29.004621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:28.540048Z","time spent":"464.557192ms","remote":"127.0.0.1:36486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3787,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e9415ac2-e3d7-457a-b913-a55bb974c66f\" "} {"level":"warn","ts":"2026-05-14T14:27:29.004721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.297433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-14T14:27:29.004586Z","caller":"traceutil/trace.go:171","msg":"trace[1671723036] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:4747; }","duration":"278.626832ms","start":"2026-05-14T14:27:28.725951Z","end":"2026-05-14T14:27:29.004578Z","steps":["trace[1671723036] 'count revisions from in-memory index tree' (duration: 278.457697ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:29.004805Z","caller":"traceutil/trace.go:171","msg":"trace[937355078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4747; }","duration":"324.407136ms","start":"2026-05-14T14:27:28.680383Z","end":"2026-05-14T14:27:29.00479Z","steps":["trace[937355078] 'range keys from in-memory index tree' (duration: 324.079456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:29.004933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:28.680369Z","time spent":"324.547671ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-14T14:27:29.004593Z","caller":"traceutil/trace.go:171","msg":"trace[497207948] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4747; }","duration":"249.018854ms","start":"2026-05-14T14:27:28.755557Z","end":"2026-05-14T14:27:29.004575Z","steps":["trace[497207948] 'range keys from in-memory index tree' (duration: 248.741105ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:29.004488Z","caller":"traceutil/trace.go:171","msg":"trace[1454420102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4747; }","duration":"340.416487ms","start":"2026-05-14T14:27:28.66405Z","end":"2026-05-14T14:27:29.004467Z","steps":["trace[1454420102] 'range keys from in-memory index tree' (duration: 339.981644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:29.005172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:28.664019Z","time spent":"341.140148ms","remote":"127.0.0.1:36558","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-05-14T14:27:29.342017Z","caller":"traceutil/trace.go:171","msg":"trace[154442317] transaction","detail":"{read_only:false; response_revision:4749; number_of_response:1; }","duration":"330.166934ms","start":"2026-05-14T14:27:29.011809Z","end":"2026-05-14T14:27:29.341976Z","steps":["trace[154442317] 'process raft request' (duration: 326.138343ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:29.342091Z","caller":"traceutil/trace.go:171","msg":"trace[165427519] transaction","detail":"{read_only:false; response_revision:4750; number_of_response:1; }","duration":"330.193754ms","start":"2026-05-14T14:27:29.011864Z","end":"2026-05-14T14:27:29.342058Z","steps":["trace[165427519] 'process raft request' (duration: 330.03488ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:29.342126Z","caller":"traceutil/trace.go:171","msg":"trace[2056394271] transaction","detail":"{read_only:false; response_revision:4751; number_of_response:1; }","duration":"330.225065ms","start":"2026-05-14T14:27:29.011894Z","end":"2026-05-14T14:27:29.342119Z","steps":["trace[2056394271] 'process raft request' (duration: 330.083291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:29.34224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:29.011722Z","time spent":"330.411451ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T14:27:29.342257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:29.011831Z","time spent":"330.325858ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T14:27:29.342065Z","caller":"traceutil/trace.go:171","msg":"trace[2083580642] transaction","detail":"{read_only:false; response_revision:4752; number_of_response:1; }","duration":"330.148303ms","start":"2026-05-14T14:27:29.011894Z","end":"2026-05-14T14:27:29.342043Z","steps":["trace[2083580642] 'process raft request' (duration: 330.110702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:29.342418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:29.011864Z","time spent":"330.506024ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T14:27:29.342541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:29.011864Z","time spent":"330.296847ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T14:27:29.347317Z","caller":"traceutil/trace.go:171","msg":"trace[703451610] linearizableReadLoop","detail":"{readStateIndex:4935; appliedIndex:4930; }","duration":"230.53717ms","start":"2026-05-14T14:27:29.116762Z","end":"2026-05-14T14:27:29.3473Z","steps":["trace[703451610] 'read index received' (duration: 221.271921ms)","trace[703451610] 'applied index is now lower than readState.Index' (duration: 9.263959ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:27:29.347445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.130055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-14T14:27:29.34745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.676544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:27:29.347514Z","caller":"traceutil/trace.go:171","msg":"trace[181561102] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4753; }","duration":"201.207637ms","start":"2026-05-14T14:27:29.146264Z","end":"2026-05-14T14:27:29.347471Z","steps":["trace[181561102] 'agreement among raft nodes before linearized reading' (duration: 201.135125ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:29.347536Z","caller":"traceutil/trace.go:171","msg":"trace[1615556232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4753; }","duration":"230.824918ms","start":"2026-05-14T14:27:29.11669Z","end":"2026-05-14T14:27:29.347515Z","steps":["trace[1615556232] 'agreement among raft nodes before linearized reading' (duration: 230.668384ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:29.347349Z","caller":"traceutil/trace.go:171","msg":"trace[1669888030] transaction","detail":"{read_only:false; response_revision:4753; number_of_response:1; }","duration":"287.284206ms","start":"2026-05-14T14:27:29.060043Z","end":"2026-05-14T14:27:29.347327Z","steps":["trace[1669888030] 'process raft request' (duration: 287.069899ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:30.199732Z","caller":"traceutil/trace.go:171","msg":"trace[1233984377] transaction","detail":"{read_only:false; response_revision:4758; number_of_response:1; }","duration":"148.15867ms","start":"2026-05-14T14:27:30.051535Z","end":"2026-05-14T14:27:30.199693Z","steps":["trace[1233984377] 'process raft request' (duration: 147.248433ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:30.199778Z","caller":"traceutil/trace.go:171","msg":"trace[252992311] linearizableReadLoop","detail":"{readStateIndex:4942; appliedIndex:4938; }","duration":"145.686426ms","start":"2026-05-14T14:27:30.054073Z","end":"2026-05-14T14:27:30.199759Z","steps":["trace[252992311] 'read index received' (duration: 9.188487ms)","trace[252992311] 'applied index is now lower than readState.Index' (duration: 136.496909ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:27:30.19981Z","caller":"traceutil/trace.go:171","msg":"trace[544178221] transaction","detail":"{read_only:false; response_revision:4759; number_of_response:1; }","duration":"148.060936ms","start":"2026-05-14T14:27:30.051718Z","end":"2026-05-14T14:27:30.199778Z","steps":["trace[544178221] 'process raft request' (duration: 147.84063ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:30.199866Z","caller":"traceutil/trace.go:171","msg":"trace[536939287] transaction","detail":"{read_only:false; response_revision:4760; number_of_response:1; }","duration":"146.841689ms","start":"2026-05-14T14:27:30.053012Z","end":"2026-05-14T14:27:30.199854Z","steps":["trace[536939287] 'process raft request' (duration: 146.684515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:30.200258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.15489ms","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-05-14T14:27:30.200303Z","caller":"traceutil/trace.go:171","msg":"trace[2015384401] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4760; }","duration":"146.234892ms","start":"2026-05-14T14:27:30.054056Z","end":"2026-05-14T14:27:30.200291Z","steps":["trace[2015384401] 'agreement among raft nodes before linearized reading' (duration: 146.011465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:30.200447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.507449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16083"} {"level":"info","ts":"2026-05-14T14:27:30.200567Z","caller":"traceutil/trace.go:171","msg":"trace[466076817] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4761; }","duration":"130.660592ms","start":"2026-05-14T14:27:30.069886Z","end":"2026-05-14T14:27:30.200546Z","steps":["trace[466076817] 'agreement among raft nodes before linearized reading' (duration: 130.331903ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:30.200504Z","caller":"traceutil/trace.go:171","msg":"trace[553100012] transaction","detail":"{read_only:false; response_revision:4761; number_of_response:1; }","duration":"129.200408ms","start":"2026-05-14T14:27:30.071285Z","end":"2026-05-14T14:27:30.200486Z","steps":["trace[553100012] 'process raft request' (duration: 128.852038ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:31.512909Z","caller":"traceutil/trace.go:171","msg":"trace[861302881] linearizableReadLoop","detail":"{readStateIndex:4958; appliedIndex:4957; }","duration":"396.454561ms","start":"2026-05-14T14:27:31.116423Z","end":"2026-05-14T14:27:31.512877Z","steps":["trace[861302881] 'read index received' (duration: 396.203093ms)","trace[861302881] 'applied index is now lower than readState.Index' (duration: 250.488µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:27:31.51304Z","caller":"traceutil/trace.go:171","msg":"trace[1551423463] transaction","detail":"{read_only:false; response_revision:4776; number_of_response:1; }","duration":"457.75896ms","start":"2026-05-14T14:27:31.055269Z","end":"2026-05-14T14:27:31.513028Z","steps":["trace[1551423463] 'process raft request' (duration: 457.42215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:31.513403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.957127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T14:27:31.513479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.033865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-14T14:27:31.513498Z","caller":"traceutil/trace.go:171","msg":"trace[1361018126] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4776; }","duration":"397.126211ms","start":"2026-05-14T14:27:31.116347Z","end":"2026-05-14T14:27:31.513474Z","steps":["trace[1361018126] 'agreement among raft nodes before linearized reading' (duration: 396.953466ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:31.513526Z","caller":"traceutil/trace.go:171","msg":"trace[2041087774] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4776; }","duration":"165.107978ms","start":"2026-05-14T14:27:31.348405Z","end":"2026-05-14T14:27:31.513513Z","steps":["trace[2041087774] 'agreement among raft nodes before linearized reading' (duration: 164.926123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:31.513469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.135888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-14T14:27:31.513547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:31.116325Z","time spent":"397.213674ms","remote":"127.0.0.1:36278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T14:27:31.513612Z","caller":"traceutil/trace.go:171","msg":"trace[1754093518] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4776; }","duration":"165.319913ms","start":"2026-05-14T14:27:31.348245Z","end":"2026-05-14T14:27:31.513565Z","steps":["trace[1754093518] 'agreement among raft nodes before linearized reading' (duration: 165.045315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:31.513881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.897781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-14T14:27:31.513909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.899891ms","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-05-14T14:27:31.513936Z","caller":"traceutil/trace.go:171","msg":"trace[1195630730] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4776; }","duration":"165.938513ms","start":"2026-05-14T14:27:31.347989Z","end":"2026-05-14T14:27:31.513928Z","steps":["trace[1195630730] 'agreement among raft nodes before linearized reading' (duration: 165.795249ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:31.513932Z","caller":"traceutil/trace.go:171","msg":"trace[617216347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4776; }","duration":"165.985664ms","start":"2026-05-14T14:27:31.347934Z","end":"2026-05-14T14:27:31.51392Z","steps":["trace[617216347] 'agreement among raft nodes before linearized reading' (duration: 165.86159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:31.513944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:31.055247Z","time spent":"457.830861ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T14:27:31.514424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.258767ms","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-05-14T14:27:31.51449Z","caller":"traceutil/trace.go:171","msg":"trace[16357560] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:4776; }","duration":"197.384791ms","start":"2026-05-14T14:27:31.317091Z","end":"2026-05-14T14:27:31.514476Z","steps":["trace[16357560] 'agreement among raft nodes before linearized reading' (duration: 196.164823ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:34.570818Z","caller":"traceutil/trace.go:171","msg":"trace[796490266] transaction","detail":"{read_only:false; response_revision:4817; number_of_response:1; }","duration":"108.608708ms","start":"2026-05-14T14:27:34.462181Z","end":"2026-05-14T14:27:34.57079Z","steps":["trace[796490266] 'process raft request' (duration: 108.471354ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:38.405455Z","caller":"traceutil/trace.go:171","msg":"trace[2043452051] transaction","detail":"{read_only:false; response_revision:4875; number_of_response:1; }","duration":"218.522853ms","start":"2026-05-14T14:27:38.186899Z","end":"2026-05-14T14:27:38.405422Z","steps":["trace[2043452051] 'process raft request' (duration: 212.222492ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:38.405635Z","caller":"traceutil/trace.go:171","msg":"trace[2084881043] transaction","detail":"{read_only:false; response_revision:4878; number_of_response:1; }","duration":"165.628142ms","start":"2026-05-14T14:27:38.23998Z","end":"2026-05-14T14:27:38.405609Z","steps":["trace[2084881043] 'process raft request' (duration: 165.590841ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:38.405676Z","caller":"traceutil/trace.go:171","msg":"trace[12436166] linearizableReadLoop","detail":"{readStateIndex:5061; appliedIndex:5060; }","duration":"206.801266ms","start":"2026-05-14T14:27:38.198862Z","end":"2026-05-14T14:27:38.405663Z","steps":["trace[12436166] 'read index received' (duration: 200.324609ms)","trace[12436166] 'applied index is now lower than readState.Index' (duration: 6.467157ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:27:38.405689Z","caller":"traceutil/trace.go:171","msg":"trace[555223611] transaction","detail":"{read_only:false; response_revision:4877; number_of_response:1; }","duration":"166.86115ms","start":"2026-05-14T14:27:38.238822Z","end":"2026-05-14T14:27:38.405683Z","steps":["trace[555223611] 'process raft request' (duration: 166.716436ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:38.40567Z","caller":"traceutil/trace.go:171","msg":"trace[1445089293] transaction","detail":"{read_only:false; response_revision:4876; number_of_response:1; }","duration":"167.51001ms","start":"2026-05-14T14:27:38.23813Z","end":"2026-05-14T14:27:38.40564Z","steps":["trace[1445089293] 'process raft request' (duration: 167.204951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:38.405846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.959992ms","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-05-14T14:27:38.40639Z","caller":"traceutil/trace.go:171","msg":"trace[638576463] 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:4878; }","duration":"207.516367ms","start":"2026-05-14T14:27:38.198832Z","end":"2026-05-14T14:27:38.406349Z","steps":["trace[638576463] 'agreement among raft nodes before linearized reading' (duration: 206.868718ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:43.450529Z","caller":"traceutil/trace.go:171","msg":"trace[2075685644] linearizableReadLoop","detail":"{readStateIndex:5110; appliedIndex:5109; }","duration":"326.006931ms","start":"2026-05-14T14:27:43.124495Z","end":"2026-05-14T14:27:43.450502Z","steps":["trace[2075685644] 'read index received' (duration: 325.734563ms)","trace[2075685644] 'applied index is now lower than readState.Index' (duration: 271.278µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:27:43.450582Z","caller":"traceutil/trace.go:171","msg":"trace[1289958977] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"356.267389ms","start":"2026-05-14T14:27:43.094273Z","end":"2026-05-14T14:27:43.45054Z","steps":["trace[1289958977] 'process raft request' (duration: 356.024832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:43.450713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.204218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:27:43.450777Z","caller":"traceutil/trace.go:171","msg":"trace[1752294007] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4923; }","duration":"326.313511ms","start":"2026-05-14T14:27:43.12445Z","end":"2026-05-14T14:27:43.450763Z","steps":["trace[1752294007] 'agreement among raft nodes before linearized reading' (duration: 326.192628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:43.450781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:43.094262Z","time spent":"356.390694ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T14:27:43.450814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:43.124434Z","time spent":"326.372412ms","remote":"127.0.0.1:36278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T14:27:43.450907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.873651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"warn","ts":"2026-05-14T14:27:43.450938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.995458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:13289"} {"level":"info","ts":"2026-05-14T14:27:43.450969Z","caller":"traceutil/trace.go:171","msg":"trace[1377324546] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4923; }","duration":"177.004135ms","start":"2026-05-14T14:27:43.273947Z","end":"2026-05-14T14:27:43.450951Z","steps":["trace[1377324546] 'agreement among raft nodes before linearized reading' (duration: 176.779788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:43.450919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.9539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:27:43.451071Z","caller":"traceutil/trace.go:171","msg":"trace[1417914573] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:4923; }","duration":"187.228938ms","start":"2026-05-14T14:27:43.263819Z","end":"2026-05-14T14:27:43.451048Z","steps":["trace[1417914573] 'agreement among raft nodes before linearized reading' (duration: 186.915138ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:43.45098Z","caller":"traceutil/trace.go:171","msg":"trace[987458805] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4923; }","duration":"228.043069ms","start":"2026-05-14T14:27:43.222924Z","end":"2026-05-14T14:27:43.450967Z","steps":["trace[987458805] 'agreement among raft nodes before linearized reading' (duration: 227.73935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:43.956557Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219825085524605,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T14:27:44.672434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.902101ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-05-14T14:27:44.672582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:43.452748Z","time spent":"1.219830833s","remote":"127.0.0.1:36328","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-14T14:27:45.4202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.873312ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T14:27:45.420554Z","caller":"traceutil/trace.go:171","msg":"trace[1457329760] linearizableReadLoop","detail":"{readStateIndex:5112; appliedIndex:5110; }","duration":"1.964049898s","start":"2026-05-14T14:27:43.456487Z","end":"2026-05-14T14:27:45.420537Z","steps":["trace[1457329760] 'read index received' (duration: 819.116505ms)","trace[1457329760] 'applied index is now lower than readState.Index' (duration: 1.144932692s)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:27:45.420638Z","caller":"traceutil/trace.go:171","msg":"trace[1851553231] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"1.955778104s","start":"2026-05-14T14:27:43.464825Z","end":"2026-05-14T14:27:45.420603Z","steps":["trace[1851553231] 'process raft request' (duration: 1.522424999s)","trace[1851553231] 'compare' (duration: 432.721796ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:27:45.420723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:43.464807Z","time spent":"1.955873227s","remote":"127.0.0.1:36486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13861,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T14:27:45.42092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.96443891s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-123f009e-858e-4882-aa49-966640017987\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-05-14T14:27:45.420958Z","caller":"traceutil/trace.go:171","msg":"trace[312512666] range","detail":"{range_begin:/registry/persistentvolumes/pvc-123f009e-858e-4882-aa49-966640017987; range_end:; response_count:1; response_revision:4925; }","duration":"1.966787183s","start":"2026-05-14T14:27:43.454162Z","end":"2026-05-14T14:27:45.420949Z","steps":["trace[312512666] 'agreement among raft nodes before linearized reading' (duration: 1.966658809s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:45.421032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:43.454146Z","time spent":"1.966875505s","remote":"127.0.0.1:36440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1472,"request content":"key:\"/registry/persistentvolumes/pvc-123f009e-858e-4882-aa49-966640017987\" "} {"level":"info","ts":"2026-05-14T14:27:45.421523Z","caller":"traceutil/trace.go:171","msg":"trace[155319503] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"1.261605773s","start":"2026-05-14T14:27:44.159844Z","end":"2026-05-14T14:27:45.42145Z","steps":["trace[155319503] 'process raft request' (duration: 1.260439487s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:45.422179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:44.159815Z","time spent":"1.261909673s","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T14:27:45.920904Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219825085524615,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T14:27:46.185059Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.197829374s","expected-duration":"1s"} {"level":"info","ts":"2026-05-14T14:27:46.210399Z","caller":"traceutil/trace.go:171","msg":"trace[1343166767] transaction","detail":"{read_only:false; response_revision:4926; number_of_response:1; }","duration":"1.784917401s","start":"2026-05-14T14:27:44.425443Z","end":"2026-05-14T14:27:46.210361Z","steps":["trace[1343166767] 'process raft request' (duration: 1.759848171s)","trace[1343166767] 'compare' (duration: 24.175053ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:27:46.210527Z","caller":"traceutil/trace.go:171","msg":"trace[949458119] transaction","detail":"{read_only:false; response_revision:4928; number_of_response:1; }","duration":"1.783041405s","start":"2026-05-14T14:27:44.427463Z","end":"2026-05-14T14:27:46.210504Z","steps":["trace[949458119] 'process raft request' (duration: 1.782890389s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:46.210592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:44.425407Z","time spent":"1.785079197s","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T14:27:46.210673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:44.427453Z","time spent":"1.783144498s","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T14:27:46.210554Z","caller":"traceutil/trace.go:171","msg":"trace[1038744650] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"1.784231371s","start":"2026-05-14T14:27:44.426187Z","end":"2026-05-14T14:27:46.210418Z","steps":["trace[1038744650] 'process raft request' (duration: 1.784053935s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:46.211093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:44.426174Z","time spent":"1.784762097s","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T14:27:46.21148Z","caller":"traceutil/trace.go:171","msg":"trace[1658155402] transaction","detail":"{read_only:false; response_revision:4929; number_of_response:1; }","duration":"1.536756617s","start":"2026-05-14T14:27:44.673729Z","end":"2026-05-14T14:27:46.210485Z","steps":["trace[1658155402] 'process raft request' (duration: 1.536680154s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:46.211655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:44.673713Z","time spent":"1.537890301s","remote":"127.0.0.1:36328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T14:27:46.228012Z","caller":"traceutil/trace.go:171","msg":"trace[848728745] linearizableReadLoop","detail":"{readStateIndex:5118; appliedIndex:5113; }","duration":"807.404549ms","start":"2026-05-14T14:27:45.420584Z","end":"2026-05-14T14:27:46.227989Z","steps":["trace[848728745] 'read index received' (duration: 764.721146ms)","trace[848728745] 'applied index is now lower than readState.Index' (duration: 42.682143ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:27:46.228266Z","caller":"traceutil/trace.go:171","msg":"trace[427712500] transaction","detail":"{read_only:false; response_revision:4930; number_of_response:1; }","duration":"1.104399785s","start":"2026-05-14T14:27:45.123829Z","end":"2026-05-14T14:27:46.228229Z","steps":["trace[427712500] 'process raft request' (duration: 1.104020233s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:46.228336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.651955225s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-05-14T14:27:46.228409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.64724136s","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-05-14T14:27:46.228436Z","caller":"traceutil/trace.go:171","msg":"trace[1126885886] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4930; }","duration":"2.65211946s","start":"2026-05-14T14:27:43.576298Z","end":"2026-05-14T14:27:46.228418Z","steps":["trace[1126885886] 'agreement among raft nodes before linearized reading' (duration: 2.651868633s)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:46.228481Z","caller":"traceutil/trace.go:171","msg":"trace[712280606] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4930; }","duration":"2.647342093s","start":"2026-05-14T14:27:43.581122Z","end":"2026-05-14T14:27:46.228464Z","steps":["trace[712280606] 'agreement among raft nodes before linearized reading' (duration: 2.647167428s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:46.228503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.699253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-05-14T14:27:46.228601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.336684478s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T14:27:46.228597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:43.576263Z","time spent":"2.652283145s","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-14T14:27:46.228653Z","caller":"traceutil/trace.go:171","msg":"trace[168672519] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:4930; }","duration":"1.336777091s","start":"2026-05-14T14:27:44.891863Z","end":"2026-05-14T14:27:46.22864Z","steps":["trace[168672519] 'agreement among raft nodes before linearized reading' (duration: 1.336694338s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:46.228668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.525947147s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T14:27:46.228708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:44.891839Z","time spent":"1.336860174s","remote":"127.0.0.1:56518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-05-14T14:27:46.228723Z","caller":"traceutil/trace.go:171","msg":"trace[466406095] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:4930; }","duration":"1.526030899s","start":"2026-05-14T14:27:44.702675Z","end":"2026-05-14T14:27:46.228706Z","steps":["trace[466406095] 'agreement among raft nodes before linearized reading' (duration: 1.525949927s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:46.228297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.112877666s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T14:27:46.228905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.650345446s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-14T14:27:46.228922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.997224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:27:46.228966Z","caller":"traceutil/trace.go:171","msg":"trace[1703979764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4930; }","duration":"2.650444859s","start":"2026-05-14T14:27:43.578507Z","end":"2026-05-14T14:27:46.228952Z","steps":["trace[1703979764] 'agreement among raft nodes before linearized reading' (duration: 2.650294314s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:46.229008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:43.578486Z","time spent":"2.650509611s","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-14T14:27:46.229015Z","caller":"traceutil/trace.go:171","msg":"trace[692924936] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:4930; }","duration":"762.054856ms","start":"2026-05-14T14:27:45.466906Z","end":"2026-05-14T14:27:46.228961Z","steps":["trace[692924936] 'agreement among raft nodes before linearized reading' (duration: 761.965043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:46.228576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:43.581112Z","time spent":"2.647450016s","remote":"127.0.0.1:36558","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-05-14T14:27:46.228482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:45.123803Z","time spent":"1.104567601s","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T14:27:46.228646Z","caller":"traceutil/trace.go:171","msg":"trace[1388094270] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4930; }","duration":"803.494758ms","start":"2026-05-14T14:27:45.425123Z","end":"2026-05-14T14:27:46.228618Z","steps":["trace[1388094270] 'agreement among raft nodes before linearized reading' (duration: 803.156858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:46.229279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:45.425103Z","time spent":"804.158659ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-05-14T14:27:46.228764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:44.702658Z","time spent":"1.526094691s","remote":"127.0.0.1:43832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true "} {"level":"warn","ts":"2026-05-14T14:27:46.229272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.991843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-14T14:27:46.229111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:45.466891Z","time spent":"762.159598ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-05-14T14:27:46.228873Z","caller":"traceutil/trace.go:171","msg":"trace[232517646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4930; }","duration":"1.113528996s","start":"2026-05-14T14:27:45.115326Z","end":"2026-05-14T14:27:46.228855Z","steps":["trace[232517646] 'agreement among raft nodes before linearized reading' (duration: 1.112878036s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:46.23206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:45.115305Z","time spent":"1.116688403s","remote":"127.0.0.1:36278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T14:27:46.229462Z","caller":"traceutil/trace.go:171","msg":"trace[1812546148] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:4930; }","duration":"241.218042ms","start":"2026-05-14T14:27:45.988221Z","end":"2026-05-14T14:27:46.229439Z","steps":["trace[1812546148] 'agreement among raft nodes before linearized reading' (duration: 240.732787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:46.228898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.651744299s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-14T14:27:46.232851Z","caller":"traceutil/trace.go:171","msg":"trace[934155009] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4930; }","duration":"2.656196945s","start":"2026-05-14T14:27:43.5766Z","end":"2026-05-14T14:27:46.232797Z","steps":["trace[934155009] 'agreement among raft nodes before linearized reading' (duration: 2.651525732s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:46.232959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:43.576589Z","time spent":"2.656351471s","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T14:27:46.229221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.191317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-14T14:27:46.233055Z","caller":"traceutil/trace.go:171","msg":"trace[364882659] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4930; }","duration":"805.039926ms","start":"2026-05-14T14:27:45.42799Z","end":"2026-05-14T14:27:46.233029Z","steps":["trace[364882659] 'agreement among raft nodes before linearized reading' (duration: 801.150126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:46.233158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:45.427977Z","time spent":"805.15886ms","remote":"127.0.0.1:36486","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":"warn","ts":"2026-05-14T14:27:46.228781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.652692838s","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-05-14T14:27:46.233435Z","caller":"traceutil/trace.go:171","msg":"trace[646137484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4930; }","duration":"2.657402472s","start":"2026-05-14T14:27:43.576016Z","end":"2026-05-14T14:27:46.233418Z","steps":["trace[646137484] 'agreement among raft nodes before linearized reading' (duration: 2.652666397s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:46.233503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:43.575984Z","time spent":"2.657503845s","remote":"127.0.0.1:36558","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-05-14T14:27:46.229601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.872391ms","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-05-14T14:27:46.233868Z","caller":"traceutil/trace.go:171","msg":"trace[1605943530] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4930; }","duration":"549.171593ms","start":"2026-05-14T14:27:45.684678Z","end":"2026-05-14T14:27:46.23385Z","steps":["trace[1605943530] 'agreement among raft nodes before linearized reading' (duration: 544.761788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:46.233943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:45.684653Z","time spent":"549.273026ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3338,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-05-14T14:27:46.229749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.535230011s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T14:27:46.234241Z","caller":"traceutil/trace.go:171","msg":"trace[2009256120] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:4930; }","duration":"1.541310658s","start":"2026-05-14T14:27:44.692899Z","end":"2026-05-14T14:27:46.23421Z","steps":["trace[2009256120] 'agreement among raft nodes before linearized reading' (duration: 1.53517033s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:46.234342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:44.692886Z","time spent":"1.541441222s","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":1,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true "} {"level":"warn","ts":"2026-05-14T14:27:46.230543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.474037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:13876"} {"level":"info","ts":"2026-05-14T14:27:46.234475Z","caller":"traceutil/trace.go:171","msg":"trace[1697659004] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4930; }","duration":"806.416949ms","start":"2026-05-14T14:27:45.428042Z","end":"2026-05-14T14:27:46.234459Z","steps":["trace[1697659004] 'agreement among raft nodes before linearized reading' (duration: 800.899949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:46.234532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:45.428034Z","time spent":"806.483061ms","remote":"127.0.0.1:36486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":13898,"request content":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-05-14T14:27:46.623674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.253614ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T14:27:46.623962Z","caller":"traceutil/trace.go:171","msg":"trace[781351373] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"386.004946ms","start":"2026-05-14T14:27:46.237928Z","end":"2026-05-14T14:27:46.623933Z","steps":["trace[781351373] 'process raft request' (duration: 220.406772ms)","trace[781351373] 'compare' (duration: 165.071598ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:27:46.624131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:46.237916Z","time spent":"386.13345ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T14:27:46.624877Z","caller":"traceutil/trace.go:171","msg":"trace[520045007] transaction","detail":"{read_only:false; response_revision:4933; number_of_response:1; }","duration":"386.81317ms","start":"2026-05-14T14:27:46.238049Z","end":"2026-05-14T14:27:46.624862Z","steps":["trace[520045007] 'process raft request' (duration: 385.730248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:46.625005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:46.23804Z","time spent":"386.880323ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T14:27:46.625124Z","caller":"traceutil/trace.go:171","msg":"trace[1026997849] transaction","detail":"{read_only:false; response_revision:4934; number_of_response:1; }","duration":"386.44639ms","start":"2026-05-14T14:27:46.238622Z","end":"2026-05-14T14:27:46.625069Z","steps":["trace[1026997849] 'process raft request' (duration: 385.935474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:46.625342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:46.238612Z","time spent":"386.622055ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T14:27:46.625599Z","caller":"traceutil/trace.go:171","msg":"trace[1137308267] transaction","detail":"{read_only:false; response_revision:4935; number_of_response:1; }","duration":"385.656605ms","start":"2026-05-14T14:27:46.239906Z","end":"2026-05-14T14:27:46.625563Z","steps":["trace[1137308267] 'process raft request' (duration: 384.963684ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:27:46.625643Z","caller":"traceutil/trace.go:171","msg":"trace[479119111] linearizableReadLoop","detail":"{readStateIndex:5123; appliedIndex:5119; }","duration":"382.183739ms","start":"2026-05-14T14:27:46.24342Z","end":"2026-05-14T14:27:46.625604Z","steps":["trace[479119111] 'read index received' (duration: 3.25348ms)","trace[479119111] 'applied index is now lower than readState.Index' (duration: 378.927599ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:27:46.626242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.812807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-14T14:27:46.626373Z","caller":"traceutil/trace.go:171","msg":"trace[749399685] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4935; }","duration":"382.958392ms","start":"2026-05-14T14:27:46.243394Z","end":"2026-05-14T14:27:46.626353Z","steps":["trace[749399685] 'agreement among raft nodes before linearized reading' (duration: 382.624861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:46.626442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:46.243389Z","time spent":"383.032485ms","remote":"127.0.0.1:36486","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":"warn","ts":"2026-05-14T14:27:46.626684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:46.239893Z","time spent":"385.81388ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T14:27:46.646035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.531405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:27:46.646136Z","caller":"traceutil/trace.go:171","msg":"trace[1947754467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4936; }","duration":"402.858086ms","start":"2026-05-14T14:27:46.243253Z","end":"2026-05-14T14:27:46.646111Z","steps":["trace[1947754467] 'agreement among raft nodes before linearized reading' (duration: 402.676211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:46.646192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:46.24324Z","time spent":"402.941778ms","remote":"127.0.0.1:36278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T14:27:46.646748Z","caller":"traceutil/trace.go:171","msg":"trace[2135966245] transaction","detail":"{read_only:false; response_revision:4936; number_of_response:1; }","duration":"397.521641ms","start":"2026-05-14T14:27:46.249202Z","end":"2026-05-14T14:27:46.646724Z","steps":["trace[2135966245] 'process raft request' (duration: 396.470728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:46.646907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:46.249174Z","time spent":"397.625084ms","remote":"127.0.0.1:36486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13647,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T14:27:47.116077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.451591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T14:27:47.116185Z","caller":"traceutil/trace.go:171","msg":"trace[413815049] linearizableReadLoop","detail":"{readStateIndex:5135; appliedIndex:5134; }","duration":"323.92493ms","start":"2026-05-14T14:27:46.792241Z","end":"2026-05-14T14:27:47.116165Z","steps":["trace[413815049] 'read index received' (duration: 201.135868ms)","trace[413815049] 'applied index is now lower than readState.Index' (duration: 122.787892ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:27:47.116526Z","caller":"traceutil/trace.go:171","msg":"trace[2132663451] transaction","detail":"{read_only:false; response_revision:4947; number_of_response:1; }","duration":"326.605304ms","start":"2026-05-14T14:27:46.789892Z","end":"2026-05-14T14:27:47.116497Z","steps":["trace[2132663451] 'process raft request' (duration: 203.640316ms)","trace[2132663451] 'compare' (duration: 122.250326ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:27:47.116697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:46.789877Z","time spent":"326.700756ms","remote":"127.0.0.1:54636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4710,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T14:27:47.117171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.902371ms","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-05-14T14:27:47.117261Z","caller":"traceutil/trace.go:171","msg":"trace[1582675468] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4947; }","duration":"325.019664ms","start":"2026-05-14T14:27:46.79222Z","end":"2026-05-14T14:27:47.11724Z","steps":["trace[1582675468] 'agreement among raft nodes before linearized reading' (duration: 324.047983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:27:47.117304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:27:46.792214Z","time spent":"325.080226ms","remote":"127.0.0.1:48462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5673,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-14T14:28:01.839781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.788626ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T14:28:01.839924Z","caller":"traceutil/trace.go:171","msg":"trace[889945999] transaction","detail":"{read_only:false; response_revision:5035; number_of_response:1; }","duration":"222.87991ms","start":"2026-05-14T14:28:01.617024Z","end":"2026-05-14T14:28:01.839903Z","steps":["trace[889945999] 'process raft request' (duration: 31.837767ms)","trace[889945999] 'compare' (duration: 190.567409ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:28:01.841754Z","caller":"traceutil/trace.go:171","msg":"trace[829065728] transaction","detail":"{read_only:false; response_revision:5037; number_of_response:1; }","duration":"223.355265ms","start":"2026-05-14T14:28:01.618374Z","end":"2026-05-14T14:28:01.841729Z","steps":["trace[829065728] 'process raft request' (duration: 223.294183ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:28:01.842059Z","caller":"traceutil/trace.go:171","msg":"trace[1923832743] transaction","detail":"{read_only:false; response_revision:5036; number_of_response:1; }","duration":"224.347006ms","start":"2026-05-14T14:28:01.617696Z","end":"2026-05-14T14:28:01.842043Z","steps":["trace[1923832743] 'process raft request' (duration: 223.862042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:28:02.158617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.820608ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T14:28:02.158737Z","caller":"traceutil/trace.go:171","msg":"trace[1950042296] transaction","detail":"{read_only:false; response_revision:5039; number_of_response:1; }","duration":"246.798516ms","start":"2026-05-14T14:28:01.911918Z","end":"2026-05-14T14:28:02.158717Z","steps":["trace[1950042296] 'process raft request' (duration: 65.76005ms)","trace[1950042296] 'compare' (duration: 180.583501ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:28:02.358429Z","caller":"traceutil/trace.go:171","msg":"trace[1283579691] transaction","detail":"{read_only:false; response_revision:5040; number_of_response:1; }","duration":"193.881409ms","start":"2026-05-14T14:28:02.164513Z","end":"2026-05-14T14:28:02.358395Z","steps":["trace[1283579691] 'process raft request' (duration: 102.504663ms)","trace[1283579691] 'compare' (duration: 90.860659ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:28:22.584089Z","caller":"traceutil/trace.go:171","msg":"trace[1148796890] transaction","detail":"{read_only:false; response_revision:5254; number_of_response:1; }","duration":"134.341948ms","start":"2026-05-14T14:28:22.449709Z","end":"2026-05-14T14:28:22.584051Z","steps":["trace[1148796890] 'process raft request' (duration: 94.471162ms)","trace[1148796890] 'compare' (duration: 39.614437ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:28:22.584375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.457618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-14T14:28:22.584431Z","caller":"traceutil/trace.go:171","msg":"trace[966932463] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5255; }","duration":"117.569181ms","start":"2026-05-14T14:28:22.466852Z","end":"2026-05-14T14:28:22.584421Z","steps":["trace[966932463] 'agreement among raft nodes before linearized reading' (duration: 117.400546ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:28:22.584195Z","caller":"traceutil/trace.go:171","msg":"trace[323684590] linearizableReadLoop","detail":"{readStateIndex:5451; appliedIndex:5449; }","duration":"117.265212ms","start":"2026-05-14T14:28:22.466892Z","end":"2026-05-14T14:28:22.584157Z","steps":["trace[323684590] 'read index received' (duration: 77.181559ms)","trace[323684590] 'applied index is now lower than readState.Index' (duration: 40.082573ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:28:22.584234Z","caller":"traceutil/trace.go:171","msg":"trace[188864639] transaction","detail":"{read_only:false; response_revision:5255; number_of_response:1; }","duration":"132.797528ms","start":"2026-05-14T14:28:22.451409Z","end":"2026-05-14T14:28:22.584207Z","steps":["trace[188864639] 'process raft request' (duration: 132.582151ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:28:36.124488Z","caller":"traceutil/trace.go:171","msg":"trace[2049357932] transaction","detail":"{read_only:false; response_revision:5355; number_of_response:1; }","duration":"358.197302ms","start":"2026-05-14T14:28:35.766235Z","end":"2026-05-14T14:28:36.124433Z","steps":["trace[2049357932] 'process raft request' (duration: 357.994586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:28:36.124631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:28:35.766217Z","time spent":"358.341516ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T14:28:39.227881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.145683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-14T14:28:39.227966Z","caller":"traceutil/trace.go:171","msg":"trace[1076128862] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5370; }","duration":"170.271257ms","start":"2026-05-14T14:28:39.057677Z","end":"2026-05-14T14:28:39.227948Z","steps":["trace[1076128862] 'range keys from in-memory index tree' (duration: 169.982568ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:28:39.22829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.707491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-14T14:28:39.228312Z","caller":"traceutil/trace.go:171","msg":"trace[1884687199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5370; }","duration":"170.784314ms","start":"2026-05-14T14:28:39.057522Z","end":"2026-05-14T14:28:39.228307Z","steps":["trace[1884687199] 'range keys from in-memory index tree' (duration: 170.498044ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:28:39.228721Z","caller":"traceutil/trace.go:171","msg":"trace[1578499152] linearizableReadLoop","detail":"{readStateIndex:5572; appliedIndex:5571; }","duration":"109.938968ms","start":"2026-05-14T14:28:39.118773Z","end":"2026-05-14T14:28:39.228712Z","steps":["trace[1578499152] 'read index received' (duration: 53.698713ms)","trace[1578499152] 'applied index is now lower than readState.Index' (duration: 56.239385ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:28:39.228834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.071473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:28:39.228855Z","caller":"traceutil/trace.go:171","msg":"trace[1690673898] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5371; }","duration":"110.140105ms","start":"2026-05-14T14:28:39.118709Z","end":"2026-05-14T14:28:39.228849Z","steps":["trace[1690673898] 'agreement among raft nodes before linearized reading' (duration: 110.036532ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:28:39.229417Z","caller":"traceutil/trace.go:171","msg":"trace[1274815955] transaction","detail":"{read_only:false; response_revision:5371; number_of_response:1; }","duration":"168.092146ms","start":"2026-05-14T14:28:39.061296Z","end":"2026-05-14T14:28:39.229388Z","steps":["trace[1274815955] 'process raft request' (duration: 111.116547ms)","trace[1274815955] 'compare' (duration: 56.04538ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:28:39.576857Z","caller":"traceutil/trace.go:171","msg":"trace[820292208] transaction","detail":"{read_only:false; response_revision:5373; number_of_response:1; }","duration":"335.720128ms","start":"2026-05-14T14:28:39.241113Z","end":"2026-05-14T14:28:39.576833Z","steps":["trace[820292208] 'process raft request' (duration: 335.545102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:28:39.57701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T14:28:39.241103Z","time spent":"335.835692ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T14:28:39.641566Z","caller":"traceutil/trace.go:171","msg":"trace[175019491] transaction","detail":"{read_only:false; response_revision:5374; number_of_response:1; }","duration":"179.381073ms","start":"2026-05-14T14:28:39.462156Z","end":"2026-05-14T14:28:39.641537Z","steps":["trace[175019491] 'process raft request' (duration: 179.227178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:28:42.76627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.722824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T14:28:42.766637Z","caller":"traceutil/trace.go:171","msg":"trace[1476779556] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:5392; }","duration":"104.164549ms","start":"2026-05-14T14:28:42.662457Z","end":"2026-05-14T14:28:42.766621Z","steps":["trace[1476779556] 'count revisions from in-memory index tree' (duration: 103.647752ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:28:51.564149Z","caller":"traceutil/trace.go:171","msg":"trace[626379160] transaction","detail":"{read_only:false; response_revision:5465; number_of_response:1; }","duration":"106.415515ms","start":"2026-05-14T14:28:51.457701Z","end":"2026-05-14T14:28:51.564117Z","steps":["trace[626379160] 'process raft request' (duration: 105.910369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:28:51.954476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.16659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-05-14T14:28:51.954557Z","caller":"traceutil/trace.go:171","msg":"trace[1250914704] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:5467; }","duration":"107.280784ms","start":"2026-05-14T14:28:51.847255Z","end":"2026-05-14T14:28:51.954536Z","steps":["trace[1250914704] 'range keys from in-memory index tree' (duration: 107.027236ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:28:52.326072Z","caller":"traceutil/trace.go:171","msg":"trace[1920857054] transaction","detail":"{read_only:false; response_revision:5469; number_of_response:1; }","duration":"101.135145ms","start":"2026-05-14T14:28:52.2249Z","end":"2026-05-14T14:28:52.326036Z","steps":["trace[1920857054] 'process raft request' (duration: 49.629617ms)","trace[1920857054] 'compare' (duration: 51.353713ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:28:59.14901Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2885} {"level":"info","ts":"2026-05-14T14:28:59.259449Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2885,"took":"109.27481ms","hash":2801613836,"current-db-size-bytes":52592640,"current-db-size":"53 MB","current-db-size-in-use-bytes":28807168,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-14T14:28:59.259586Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2801613836,"revision":2885,"compact-revision":-1} {"level":"info","ts":"2026-05-14T14:29:24.318702Z","caller":"traceutil/trace.go:171","msg":"trace[1463684385] transaction","detail":"{read_only:false; response_revision:5713; number_of_response:1; }","duration":"104.984351ms","start":"2026-05-14T14:29:24.213679Z","end":"2026-05-14T14:29:24.318663Z","steps":["trace[1463684385] 'process raft request' (duration: 104.797665ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:30:22.179669Z","caller":"traceutil/trace.go:171","msg":"trace[577178144] linearizableReadLoop","detail":"{readStateIndex:6227; appliedIndex:6226; }","duration":"146.831584ms","start":"2026-05-14T14:30:22.032821Z","end":"2026-05-14T14:30:22.179652Z","steps":["trace[577178144] 'read index received' (duration: 76.163091ms)","trace[577178144] 'applied index is now lower than readState.Index' (duration: 70.667983ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:30:22.179728Z","caller":"traceutil/trace.go:171","msg":"trace[140372469] transaction","detail":"{read_only:false; response_revision:6005; number_of_response:1; }","duration":"156.503964ms","start":"2026-05-14T14:30:22.023194Z","end":"2026-05-14T14:30:22.179698Z","steps":["trace[140372469] 'process raft request' (duration: 85.749459ms)","trace[140372469] 'compare' (duration: 70.511977ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:30:22.17983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.999029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:675"} {"level":"info","ts":"2026-05-14T14:30:22.179855Z","caller":"traceutil/trace.go:171","msg":"trace[1998714564] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:6005; }","duration":"147.078082ms","start":"2026-05-14T14:30:22.032769Z","end":"2026-05-14T14:30:22.179847Z","steps":["trace[1998714564] 'agreement among raft nodes before linearized reading' (duration: 146.976329ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:31:26.454646Z","caller":"traceutil/trace.go:171","msg":"trace[2088916318] transaction","detail":"{read_only:false; response_revision:6539; number_of_response:1; }","duration":"146.110152ms","start":"2026-05-14T14:31:26.308515Z","end":"2026-05-14T14:31:26.454625Z","steps":["trace[2088916318] 'process raft request' (duration: 84.07795ms)","trace[2088916318] 'compare' (duration: 61.67843ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:31:31.230399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.550117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:31:31.230463Z","caller":"traceutil/trace.go:171","msg":"trace[301775907] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6564; }","duration":"114.64778ms","start":"2026-05-14T14:31:31.115801Z","end":"2026-05-14T14:31:31.230448Z","steps":["trace[301775907] 'range keys from in-memory index tree' (duration: 114.498965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:31:31.230655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.939545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:12299"} {"level":"info","ts":"2026-05-14T14:31:31.230783Z","caller":"traceutil/trace.go:171","msg":"trace[1469368705] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:6564; }","duration":"116.10057ms","start":"2026-05-14T14:31:31.114658Z","end":"2026-05-14T14:31:31.230759Z","steps":["trace[1469368705] 'range keys from in-memory index tree' (duration: 115.721267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:31:56.884063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.49855ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T14:31:56.884185Z","caller":"traceutil/trace.go:171","msg":"trace[1536099929] linearizableReadLoop","detail":"{readStateIndex:7003; appliedIndex:7002; }","duration":"218.404464ms","start":"2026-05-14T14:31:56.665764Z","end":"2026-05-14T14:31:56.884169Z","steps":["trace[1536099929] 'read index received' (duration: 66.753459ms)","trace[1536099929] 'applied index is now lower than readState.Index' (duration: 151.650045ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:31:56.884443Z","caller":"traceutil/trace.go:171","msg":"trace[562570034] transaction","detail":"{read_only:false; response_revision:6753; number_of_response:1; }","duration":"219.034076ms","start":"2026-05-14T14:31:56.665394Z","end":"2026-05-14T14:31:56.884428Z","steps":["trace[562570034] 'process raft request' (duration: 67.096592ms)","trace[562570034] 'compare' (duration: 151.266261ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:31:56.88479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.015865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-14T14:31:56.884838Z","caller":"traceutil/trace.go:171","msg":"trace[1015409088] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6753; }","duration":"219.066847ms","start":"2026-05-14T14:31:56.66576Z","end":"2026-05-14T14:31:56.884827Z","steps":["trace[1015409088] 'agreement among raft nodes before linearized reading' (duration: 218.918432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:31:56.885091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.835732ms","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-05-14T14:31:56.88513Z","caller":"traceutil/trace.go:171","msg":"trace[216736994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6753; }","duration":"181.904205ms","start":"2026-05-14T14:31:56.703218Z","end":"2026-05-14T14:31:56.885122Z","steps":["trace[216736994] 'agreement among raft nodes before linearized reading' (duration: 181.804441ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:32:28.735329Z","caller":"traceutil/trace.go:171","msg":"trace[736137358] transaction","detail":"{read_only:false; response_revision:6939; number_of_response:1; }","duration":"101.899289ms","start":"2026-05-14T14:32:28.633124Z","end":"2026-05-14T14:32:28.735023Z","steps":["trace[736137358] 'process raft request' (duration: 101.672146ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:32:28.876581Z","caller":"traceutil/trace.go:171","msg":"trace[626860978] transaction","detail":"{read_only:false; response_revision:6940; number_of_response:1; }","duration":"136.077027ms","start":"2026-05-14T14:32:28.740486Z","end":"2026-05-14T14:32:28.876563Z","steps":["trace[626860978] 'process raft request' (duration: 63.818763ms)","trace[626860978] 'compare' (duration: 71.799207ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:32:32.875805Z","caller":"traceutil/trace.go:171","msg":"trace[2012237148] linearizableReadLoop","detail":"{readStateIndex:7216; appliedIndex:7215; }","duration":"116.500642ms","start":"2026-05-14T14:32:32.759272Z","end":"2026-05-14T14:32:32.875773Z","steps":["trace[2012237148] 'read index received' (duration: 116.305438ms)","trace[2012237148] 'applied index is now lower than readState.Index' (duration: 193.444µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:32:32.87606Z","caller":"traceutil/trace.go:171","msg":"trace[1922954137] transaction","detail":"{read_only:false; response_revision:6959; number_of_response:1; }","duration":"116.869596ms","start":"2026-05-14T14:32:32.759001Z","end":"2026-05-14T14:32:32.87587Z","steps":["trace[1922954137] 'process raft request' (duration: 116.545501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:32:32.876198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.890817ms","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-05-14T14:32:32.876324Z","caller":"traceutil/trace.go:171","msg":"trace[404509879] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6959; }","duration":"117.08046ms","start":"2026-05-14T14:32:32.759219Z","end":"2026-05-14T14:32:32.876299Z","steps":["trace[404509879] 'agreement among raft nodes before linearized reading' (duration: 116.670305ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:32:37.985419Z","caller":"traceutil/trace.go:171","msg":"trace[769242511] transaction","detail":"{read_only:false; response_revision:6985; number_of_response:1; }","duration":"225.037007ms","start":"2026-05-14T14:32:37.760365Z","end":"2026-05-14T14:32:37.985402Z","steps":["trace[769242511] 'process raft request' (duration: 224.910536ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:32:37.986959Z","caller":"traceutil/trace.go:171","msg":"trace[324935859] transaction","detail":"{read_only:false; response_revision:6986; number_of_response:1; }","duration":"177.886062ms","start":"2026-05-14T14:32:37.809054Z","end":"2026-05-14T14:32:37.98694Z","steps":["trace[324935859] 'process raft request' (duration: 177.776141ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:33:59.174783Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5533} {"level":"info","ts":"2026-05-14T14:33:59.254888Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5533,"took":"78.927118ms","hash":2159476493,"current-db-size-bytes":52592640,"current-db-size":"53 MB","current-db-size-in-use-bytes":18472960,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-14T14:33:59.254966Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2159476493,"revision":5533,"compact-revision":2885} {"level":"info","ts":"2026-05-14T14:34:23.049269Z","caller":"traceutil/trace.go:171","msg":"trace[814005663] transaction","detail":"{read_only:false; response_revision:7515; number_of_response:1; }","duration":"138.1068ms","start":"2026-05-14T14:34:22.911136Z","end":"2026-05-14T14:34:23.049242Z","steps":["trace[814005663] 'process raft request' (duration: 137.927666ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:34:29.905151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.196226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T14:34:29.905236Z","caller":"traceutil/trace.go:171","msg":"trace[1735878829] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:7550; }","duration":"111.327399ms","start":"2026-05-14T14:34:29.793892Z","end":"2026-05-14T14:34:29.905219Z","steps":["trace[1735878829] 'count revisions from in-memory index tree' (duration: 111.132705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:34:29.905213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.612684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/secretgen-controller/\" range_end:\"/registry/secrets/secretgen-controller0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:34:29.905297Z","caller":"traceutil/trace.go:171","msg":"trace[1448777742] range","detail":"{range_begin:/registry/secrets/secretgen-controller/; range_end:/registry/secrets/secretgen-controller0; response_count:0; response_revision:7550; }","duration":"124.738437ms","start":"2026-05-14T14:34:29.780538Z","end":"2026-05-14T14:34:29.905277Z","steps":["trace[1448777742] 'range keys from in-memory index tree' (duration: 124.554933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:34:29.905298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.605654ms","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-05-14T14:34:29.905487Z","caller":"traceutil/trace.go:171","msg":"trace[1611302557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7550; }","duration":"110.807198ms","start":"2026-05-14T14:34:29.794662Z","end":"2026-05-14T14:34:29.905469Z","steps":["trace[1611302557] 'range keys from in-memory index tree' (duration: 110.452381ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:35:02.264863Z","caller":"traceutil/trace.go:171","msg":"trace[2132539806] linearizableReadLoop","detail":"{readStateIndex:8002; appliedIndex:8001; }","duration":"163.346878ms","start":"2026-05-14T14:35:02.101491Z","end":"2026-05-14T14:35:02.264838Z","steps":["trace[2132539806] 'read index received' (duration: 87.284898ms)","trace[2132539806] 'applied index is now lower than readState.Index' (duration: 76.06091ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:35:02.264931Z","caller":"traceutil/trace.go:171","msg":"trace[1313153073] transaction","detail":"{read_only:false; response_revision:7714; number_of_response:1; }","duration":"165.262132ms","start":"2026-05-14T14:35:02.099638Z","end":"2026-05-14T14:35:02.2649Z","steps":["trace[1313153073] 'process raft request' (duration: 89.12777ms)","trace[1313153073] 'compare' (duration: 75.979199ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:35:02.265067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.557904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/system::leader-locking-kube-controller-manager\" ","response":"range_response_count:1 size:688"} {"level":"info","ts":"2026-05-14T14:35:02.265136Z","caller":"traceutil/trace.go:171","msg":"trace[1880798875] range","detail":"{range_begin:/registry/roles/kube-system/system::leader-locking-kube-controller-manager; range_end:; response_count:1; response_revision:7714; }","duration":"163.662736ms","start":"2026-05-14T14:35:02.101453Z","end":"2026-05-14T14:35:02.265116Z","steps":["trace[1880798875] 'agreement among raft nodes before linearized reading' (duration: 163.537412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:35:02.277073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.76294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T14:35:02.277241Z","caller":"traceutil/trace.go:171","msg":"trace[497512454] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7715; }","duration":"160.955874ms","start":"2026-05-14T14:35:02.11626Z","end":"2026-05-14T14:35:02.277216Z","steps":["trace[497512454] 'agreement among raft nodes before linearized reading' (duration: 160.76777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:35:02.277137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.604034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/system::leader-locking-kube-scheduler\" ","response":"range_response_count:1 size:661"} {"level":"info","ts":"2026-05-14T14:35:02.277439Z","caller":"traceutil/trace.go:171","msg":"trace[452540835] range","detail":"{range_begin:/registry/roles/kube-system/system::leader-locking-kube-scheduler; range_end:; response_count:1; response_revision:7715; }","duration":"142.976542ms","start":"2026-05-14T14:35:02.134446Z","end":"2026-05-14T14:35:02.277423Z","steps":["trace[452540835] 'agreement among raft nodes before linearized reading' (duration: 142.558993ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T14:35:13.998549Z","caller":"traceutil/trace.go:171","msg":"trace[1592845265] linearizableReadLoop","detail":"{readStateIndex:8063; appliedIndex:8062; }","duration":"114.983502ms","start":"2026-05-14T14:35:13.883539Z","end":"2026-05-14T14:35:13.998523Z","steps":["trace[1592845265] 'read index received' (duration: 37.734ms)","trace[1592845265] 'applied index is now lower than readState.Index' (duration: 77.248082ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T14:35:13.99861Z","caller":"traceutil/trace.go:171","msg":"trace[602331264] transaction","detail":"{read_only:false; response_revision:7772; number_of_response:1; }","duration":"119.671961ms","start":"2026-05-14T14:35:13.878908Z","end":"2026-05-14T14:35:13.99858Z","steps":["trace[602331264] 'process raft request' (duration: 42.396908ms)","trace[602331264] 'compare' (duration: 77.098988ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T14:35:13.998796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.243248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-14T14:35:13.998838Z","caller":"traceutil/trace.go:171","msg":"trace[966949798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7772; }","duration":"115.339161ms","start":"2026-05-14T14:35:13.883489Z","end":"2026-05-14T14:35:13.998828Z","steps":["trace[966949798] 'agreement among raft nodes before linearized reading' (duration: 115.179947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T14:35:18.859005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.922387ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"}