{"level":"warn","ts":"2026-06-16T00:51:01.805987Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-16T00:51:01.806084Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.74: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.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.74:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-16T00:51:01.806265Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-16T00:51:01.806287Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-06-16T00:51:01.806354Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-16T00:51:01.807274Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-06-16T00:51:01.807455Z","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.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.74:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-16T00:51:01.810777Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.999911ms"} {"level":"info","ts":"2026-06-16T00:51:01.816485Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-06-16T00:51:01.81654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-06-16T00:51:01.816556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-06-16T00:51:01.81657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-16T00:51:01.816624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-06-16T00:51:01.816685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-06-16T00:51:01.827557Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-16T00:51:01.829818Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-16T00:51:01.832498Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-16T00:51:01.834892Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-16T00:51:01.835295Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-16T00:51:01.83554Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-16T00:51:01.835755Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-16T00:51:01.836269Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-16T00:51:01.837081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-06-16T00:51:01.838086Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-06-16T00:51:01.840469Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-16T00:51:01.840645Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-06-16T00:51:01.840672Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-06-16T00:51:01.842044Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-16T00:51:01.841992Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-16T00:51:02.018273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-06-16T00:51:02.018338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-16T00:51:02.018367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-06-16T00:51:02.018397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-06-16T00:51:02.018409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-06-16T00:51:02.01842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-06-16T00:51:02.018432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-06-16T00:51:02.019382Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T00:51:02.02028Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-06-16T00:51:02.020349Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-16T00:51:02.020346Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-16T00:51:02.020742Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T00:51:02.020953Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T00:51:02.020982Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T00:51:02.020589Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-16T00:51:02.023173Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-16T00:51:02.024457Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"info","ts":"2026-06-16T00:51:02.024763Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-16T00:51:08.035876Z","caller":"traceutil/trace.go:171","msg":"trace[918792662] transaction","detail":"{read_only:false; number_of_response:0; response_revision:278; }","duration":"114.569742ms","start":"2026-06-16T00:51:07.921264Z","end":"2026-06-16T00:51:08.035834Z","steps":["trace[918792662] 'process raft request' (duration: 114.470251ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:51:08.035838Z","caller":"traceutil/trace.go:171","msg":"trace[795856325] transaction","detail":"{read_only:false; number_of_response:0; response_revision:278; }","duration":"114.727903ms","start":"2026-06-16T00:51:07.921084Z","end":"2026-06-16T00:51:08.035812Z","steps":["trace[795856325] 'process raft request' (duration: 114.574841ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:51:08.03608Z","caller":"traceutil/trace.go:171","msg":"trace[1961070098] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"101.928823ms","start":"2026-06-16T00:51:07.934139Z","end":"2026-06-16T00:51:08.036067Z","steps":["trace[1961070098] 'process raft request' (duration: 101.66085ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:51:08.036183Z","caller":"traceutil/trace.go:171","msg":"trace[2050175748] transaction","detail":"{read_only:false; number_of_response:0; response_revision:278; }","duration":"110.219707ms","start":"2026-06-16T00:51:07.925949Z","end":"2026-06-16T00:51:08.036169Z","steps":["trace[2050175748] 'process raft request' (duration: 109.823073ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:51:14.086517Z","caller":"traceutil/trace.go:171","msg":"trace[1168713500] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"109.037446ms","start":"2026-06-16T00:51:13.977454Z","end":"2026-06-16T00:51:14.086491Z","steps":["trace[1168713500] 'process raft request' (duration: 108.867914ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:51:18.428189Z","caller":"traceutil/trace.go:171","msg":"trace[2046339911] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"113.97176ms","start":"2026-06-16T00:51:18.314194Z","end":"2026-06-16T00:51:18.428166Z","steps":["trace[2046339911] 'process raft request' (duration: 113.830488ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:51:26.110195Z","caller":"traceutil/trace.go:171","msg":"trace[774638120] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"123.208783ms","start":"2026-06-16T00:51:25.986963Z","end":"2026-06-16T00:51:26.110172Z","steps":["trace[774638120] 'process raft request' (duration: 123.137062ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:51:26.11024Z","caller":"traceutil/trace.go:171","msg":"trace[2026504477] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"126.402275ms","start":"2026-06-16T00:51:25.983782Z","end":"2026-06-16T00:51:26.110184Z","steps":["trace[2026504477] 'process raft request' (duration: 81.350568ms)","trace[2026504477] 'compare' (duration: 44.830765ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:51:26.630797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.097989ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:4183"} {"level":"info","ts":"2026-06-16T00:51:26.630893Z","caller":"traceutil/trace.go:171","msg":"trace[512150159] transaction","detail":"{read_only:false; number_of_response:1; response_revision:425; }","duration":"428.455452ms","start":"2026-06-16T00:51:26.202424Z","end":"2026-06-16T00:51:26.630879Z","steps":["trace[512150159] 'process raft request' (duration: 15.904038ms)","trace[512150159] 'compare' (duration: 412.042359ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:51:26.631079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:51:26.202414Z","time spent":"428.515442ms","remote":"127.0.0.1:58064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":45,"response count":0,"response size":4206,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:51:32.00107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.560418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-16T00:51:32.001076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.412083ms","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-06-16T00:51:32.001185Z","caller":"traceutil/trace.go:171","msg":"trace[568082890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:436; }","duration":"298.70525ms","start":"2026-06-16T00:51:31.702457Z","end":"2026-06-16T00:51:32.001162Z","steps":["trace[568082890] 'range keys from in-memory index tree' (duration: 298.339066ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:51:32.001239Z","caller":"traceutil/trace.go:171","msg":"trace[1659517848] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:436; }","duration":"246.668396ms","start":"2026-06-16T00:51:31.754537Z","end":"2026-06-16T00:51:32.001206Z","steps":["trace[1659517848] 'range keys from in-memory index tree' (duration: 246.234941ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:51:36.161244Z","caller":"traceutil/trace.go:171","msg":"trace[1693916880] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"111.068601ms","start":"2026-06-16T00:51:36.050139Z","end":"2026-06-16T00:51:36.161207Z","steps":["trace[1693916880] 'process raft request' (duration: 108.933171ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:51:36.163311Z","caller":"traceutil/trace.go:171","msg":"trace[1140751045] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"113.094812ms","start":"2026-06-16T00:51:36.050196Z","end":"2026-06-16T00:51:36.163291Z","steps":["trace[1140751045] 'process raft request' (duration: 112.968731ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:51:40.302311Z","caller":"traceutil/trace.go:171","msg":"trace[1886887275] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"401.328722ms","start":"2026-06-16T00:51:39.900959Z","end":"2026-06-16T00:51:40.302288Z","steps":["trace[1886887275] 'process raft request' (duration: 390.451986ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:51:40.302436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:51:39.900929Z","time spent":"401.448104ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T00:51:40.304963Z","caller":"traceutil/trace.go:171","msg":"trace[45499109] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"384.776021ms","start":"2026-06-16T00:51:39.920177Z","end":"2026-06-16T00:51:40.304953Z","steps":["trace[45499109] 'process raft request' (duration: 384.69397ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:51:40.305062Z","caller":"traceutil/trace.go:171","msg":"trace[639470774] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:613; }","duration":"363.601322ms","start":"2026-06-16T00:51:39.941438Z","end":"2026-06-16T00:51:40.305039Z","steps":["trace[639470774] 'read index received' (duration: 349.973359ms)","trace[639470774] 'applied index is now lower than readState.Index' (duration: 13.626553ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:51:40.305092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:51:39.92016Z","time spent":"384.893412ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4068,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:51:40.305292Z","caller":"traceutil/trace.go:171","msg":"trace[900563170] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"204.999179ms","start":"2026-06-16T00:51:40.100065Z","end":"2026-06-16T00:51:40.305065Z","steps":["trace[900563170] 'process raft request' (duration: 204.861687ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:51:40.305575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.087747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-06-16T00:51:40.305638Z","caller":"traceutil/trace.go:171","msg":"trace[1567128655] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:588; }","duration":"364.186498ms","start":"2026-06-16T00:51:39.941432Z","end":"2026-06-16T00:51:40.305618Z","steps":["trace[1567128655] 'agreement among raft nodes before linearized reading' (duration: 363.740564ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:51:40.30575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:51:39.941371Z","time spent":"364.36234ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":1,"response size":7731,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-06-16T00:51:40.31277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.871985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:51:40.313049Z","caller":"traceutil/trace.go:171","msg":"trace[49234482] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:589; }","duration":"115.966316ms","start":"2026-06-16T00:51:40.196869Z","end":"2026-06-16T00:51:40.312835Z","steps":["trace[49234482] 'agreement among raft nodes before linearized reading' (duration: 115.858505ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:51:40.313395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.615582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:51:40.313441Z","caller":"traceutil/trace.go:171","msg":"trace[1702905275] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:589; }","duration":"116.667872ms","start":"2026-06-16T00:51:40.196762Z","end":"2026-06-16T00:51:40.31343Z","steps":["trace[1702905275] 'agreement among raft nodes before linearized reading' (duration: 116.594682ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:51:40.312765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.36224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T00:51:40.313832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.026082ms","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-06-16T00:51:40.313876Z","caller":"traceutil/trace.go:171","msg":"trace[952370790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:589; }","duration":"135.085584ms","start":"2026-06-16T00:51:40.178778Z","end":"2026-06-16T00:51:40.313864Z","steps":["trace[952370790] 'agreement among raft nodes before linearized reading' (duration: 135.022173ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:51:40.313987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.277365ms","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-06-16T00:51:40.314013Z","caller":"traceutil/trace.go:171","msg":"trace[235921852] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:589; }","duration":"136.337746ms","start":"2026-06-16T00:51:40.177667Z","end":"2026-06-16T00:51:40.314005Z","steps":["trace[235921852] 'agreement among raft nodes before linearized reading' (duration: 136.297406ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:51:40.313972Z","caller":"traceutil/trace.go:171","msg":"trace[883633722] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:589; }","duration":"116.411881ms","start":"2026-06-16T00:51:40.197344Z","end":"2026-06-16T00:51:40.313756Z","steps":["trace[883633722] 'agreement among raft nodes before linearized reading' (duration: 115.31999ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:51:40.314155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.149749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-16T00:51:40.314185Z","caller":"traceutil/trace.go:171","msg":"trace[1795705772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:589; }","duration":"257.19734ms","start":"2026-06-16T00:51:40.056981Z","end":"2026-06-16T00:51:40.314178Z","steps":["trace[1795705772] 'agreement among raft nodes before linearized reading' (duration: 257.144919ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:51:40.314378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.743253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-kmhqm\" ","response":"range_response_count:1 size:4884"} {"level":"info","ts":"2026-06-16T00:51:40.314416Z","caller":"traceutil/trace.go:171","msg":"trace[194429126] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-kmhqm; range_end:; response_count:1; response_revision:589; }","duration":"317.829004ms","start":"2026-06-16T00:51:39.996577Z","end":"2026-06-16T00:51:40.314406Z","steps":["trace[194429126] 'agreement among raft nodes before linearized reading' (duration: 317.696743ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:51:40.314452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:51:39.99655Z","time spent":"317.890624ms","remote":"127.0.0.1:57816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4907,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-kmhqm\" "} {"level":"info","ts":"2026-06-16T00:51:45.763614Z","caller":"traceutil/trace.go:171","msg":"trace[1756479169] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"112.820931ms","start":"2026-06-16T00:51:45.650765Z","end":"2026-06-16T00:51:45.763586Z","steps":["trace[1756479169] 'read index received' (duration: 112.647099ms)","trace[1756479169] 'applied index is now lower than readState.Index' (duration: 173.072µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:51:45.763727Z","caller":"traceutil/trace.go:171","msg":"trace[1186075424] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"114.77188ms","start":"2026-06-16T00:51:45.648944Z","end":"2026-06-16T00:51:45.763716Z","steps":["trace[1186075424] 'process raft request' (duration: 114.499838ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:51:45.76406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.279855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-16T00:51:45.76414Z","caller":"traceutil/trace.go:171","msg":"trace[425069665] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:645; }","duration":"113.398016ms","start":"2026-06-16T00:51:45.650724Z","end":"2026-06-16T00:51:45.764122Z","steps":["trace[425069665] 'agreement among raft nodes before linearized reading' (duration: 113.224954ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:51:46.793785Z","caller":"traceutil/trace.go:171","msg":"trace[948799344] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:684; }","duration":"433.543277ms","start":"2026-06-16T00:51:46.36022Z","end":"2026-06-16T00:51:46.793763Z","steps":["trace[948799344] 'read index received' (duration: 433.267254ms)","trace[948799344] 'applied index is now lower than readState.Index' (duration: 275.043µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:51:46.793962Z","caller":"traceutil/trace.go:171","msg":"trace[36177028] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"437.204413ms","start":"2026-06-16T00:51:46.356737Z","end":"2026-06-16T00:51:46.793941Z","steps":["trace[36177028] 'process raft request' (duration: 436.822529ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:51:46.79408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:51:46.35672Z","time spent":"437.281154ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:51:46.794324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.077182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-16T00:51:46.794335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.014352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-16T00:51:46.794408Z","caller":"traceutil/trace.go:171","msg":"trace[318568577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:655; }","duration":"434.205534ms","start":"2026-06-16T00:51:46.360184Z","end":"2026-06-16T00:51:46.794389Z","steps":["trace[318568577] 'agreement among raft nodes before linearized reading' (duration: 434.011962ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:51:46.794455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:51:46.360167Z","time spent":"434.280365ms","remote":"127.0.0.1:57888","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-06-16T00:51:46.794457Z","caller":"traceutil/trace.go:171","msg":"trace[1177216799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:655; }","duration":"431.169055ms","start":"2026-06-16T00:51:46.363265Z","end":"2026-06-16T00:51:46.794434Z","steps":["trace[1177216799] 'agreement among raft nodes before linearized reading' (duration: 430.923322ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:51:46.794514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:51:46.363258Z","time spent":"431.241975ms","remote":"127.0.0.1:57888","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-06-16T00:51:47.196036Z","caller":"traceutil/trace.go:171","msg":"trace[1605097128] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"167.590522ms","start":"2026-06-16T00:51:47.028414Z","end":"2026-06-16T00:51:47.196005Z","steps":["trace[1605097128] 'process raft request' (duration: 167.36117ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:51:47.916717Z","caller":"traceutil/trace.go:171","msg":"trace[644055402] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"129.35375ms","start":"2026-06-16T00:51:47.787343Z","end":"2026-06-16T00:51:47.916697Z","steps":["trace[644055402] 'process raft request' (duration: 129.201029ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:51:48.330674Z","caller":"traceutil/trace.go:171","msg":"trace[1141410138] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"244.316329ms","start":"2026-06-16T00:51:48.086333Z","end":"2026-06-16T00:51:48.330649Z","steps":["trace[1141410138] 'process raft request' (duration: 244.181967ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:51:49.396023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.98947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-06-16T00:51:49.396234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.807837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-16T00:51:49.39635Z","caller":"traceutil/trace.go:171","msg":"trace[523818568] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:661; }","duration":"598.006049ms","start":"2026-06-16T00:51:48.798326Z","end":"2026-06-16T00:51:49.396332Z","steps":["trace[523818568] 'range keys from in-memory index tree' (duration: 597.665035ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:51:49.396196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.725583ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:51:49.396481Z","caller":"traceutil/trace.go:171","msg":"trace[898819023] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:661; }","duration":"759.014186ms","start":"2026-06-16T00:51:48.63744Z","end":"2026-06-16T00:51:49.396454Z","steps":["trace[898819023] 'range keys from in-memory index tree' (duration: 758.706023ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:51:49.396455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:51:48.798306Z","time spent":"598.13161ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-16T00:51:49.396342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.251837ms","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-06-16T00:51:49.396098Z","caller":"traceutil/trace.go:171","msg":"trace[859293019] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:661; }","duration":"181.107751ms","start":"2026-06-16T00:51:49.214973Z","end":"2026-06-16T00:51:49.396081Z","steps":["trace[859293019] 'range keys from in-memory index tree' (duration: 180.819138ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:51:49.396909Z","caller":"traceutil/trace.go:171","msg":"trace[1343185878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:661; }","duration":"538.837923ms","start":"2026-06-16T00:51:48.858038Z","end":"2026-06-16T00:51:49.396875Z","steps":["trace[1343185878] 'range keys from in-memory index tree' (duration: 538.118646ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:51:49.39702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.020588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-16T00:51:49.3971Z","caller":"traceutil/trace.go:171","msg":"trace[65109086] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:661; }","duration":"524.1466ms","start":"2026-06-16T00:51:48.872937Z","end":"2026-06-16T00:51:49.397084Z","steps":["trace[65109086] 'range keys from in-memory index tree' (duration: 522.722685ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:51:49.397109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:51:48.85802Z","time spent":"539.063205ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-16T00:51:49.397164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:51:48.87292Z","time spent":"524.229131ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-16T00:52:06.574253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.276366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rd25s\" ","response":"range_response_count:1 size:3964"} {"level":"warn","ts":"2026-06-16T00:52:06.574368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.425449ms","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-06-16T00:52:06.574471Z","caller":"traceutil/trace.go:171","msg":"trace[1832307743] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:774; }","duration":"548.544734ms","start":"2026-06-16T00:52:06.025885Z","end":"2026-06-16T00:52:06.57443Z","steps":["trace[1832307743] 'range keys from in-memory index tree' (duration: 548.051722ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:52:06.57452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:52:06.025856Z","time spent":"548.652709ms","remote":"127.0.0.1:57888","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-06-16T00:52:06.574481Z","caller":"traceutil/trace.go:171","msg":"trace[520978724] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rd25s; range_end:; response_count:1; response_revision:774; }","duration":"449.505246ms","start":"2026-06-16T00:52:06.124913Z","end":"2026-06-16T00:52:06.574418Z","steps":["trace[520978724] 'range keys from in-memory index tree' (duration: 449.124999ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:52:06.574639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:52:06.124896Z","time spent":"449.725136ms","remote":"127.0.0.1:57816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rd25s\" "} {"level":"info","ts":"2026-06-16T00:52:35.139945Z","caller":"traceutil/trace.go:171","msg":"trace[397960190] linearizableReadLoop","detail":"{readStateIndex:1214; appliedIndex:1213; }","duration":"170.108771ms","start":"2026-06-16T00:52:34.969808Z","end":"2026-06-16T00:52:35.139917Z","steps":["trace[397960190] 'read index received' (duration: 169.937494ms)","trace[397960190] 'applied index is now lower than readState.Index' (duration: 170.146µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:52:35.140014Z","caller":"traceutil/trace.go:171","msg":"trace[215318694] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"314.087935ms","start":"2026-06-16T00:52:34.825905Z","end":"2026-06-16T00:52:35.139993Z","steps":["trace[215318694] 'process raft request' (duration: 313.786974ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:52:35.140413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:52:34.825884Z","time spent":"314.214681ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:52:35.140876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.046167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-16T00:52:35.140946Z","caller":"traceutil/trace.go:171","msg":"trace[1168916969] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:1172; }","duration":"171.153921ms","start":"2026-06-16T00:52:34.969775Z","end":"2026-06-16T00:52:35.140929Z","steps":["trace[1168916969] 'agreement among raft nodes before linearized reading' (duration: 170.285797ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:53:04.744102Z","caller":"traceutil/trace.go:171","msg":"trace[620978494] transaction","detail":"{read_only:false; response_revision:1532; number_of_response:1; }","duration":"118.572345ms","start":"2026-06-16T00:53:04.625498Z","end":"2026-06-16T00:53:04.744071Z","steps":["trace[620978494] 'process raft request' (duration: 118.249644ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:53:06.708162Z","caller":"traceutil/trace.go:171","msg":"trace[1621392200] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1541; }","duration":"103.717141ms","start":"2026-06-16T00:53:06.604415Z","end":"2026-06-16T00:53:06.708132Z","steps":["trace[1621392200] 'process raft request' (duration: 103.605857ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:53:07.288953Z","caller":"traceutil/trace.go:171","msg":"trace[1155327160] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1541; }","duration":"115.598489ms","start":"2026-06-16T00:53:07.173329Z","end":"2026-06-16T00:53:07.288928Z","steps":["trace[1155327160] 'process raft request' (duration: 115.483686ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:53:07.564174Z","caller":"traceutil/trace.go:171","msg":"trace[867333053] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1542; }","duration":"120.245925ms","start":"2026-06-16T00:53:07.443901Z","end":"2026-06-16T00:53:07.564147Z","steps":["trace[867333053] 'process raft request' (duration: 120.128081ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:53:12.914126Z","caller":"traceutil/trace.go:171","msg":"trace[1084122804] linearizableReadLoop","detail":"{readStateIndex:1755; appliedIndex:1754; }","duration":"205.236739ms","start":"2026-06-16T00:53:12.708865Z","end":"2026-06-16T00:53:12.914101Z","steps":["trace[1084122804] 'read index received' (duration: 204.918369ms)","trace[1084122804] 'applied index is now lower than readState.Index' (duration: 317.52µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:53:12.914203Z","caller":"traceutil/trace.go:171","msg":"trace[452837114] transaction","detail":"{read_only:false; response_revision:1683; number_of_response:1; }","duration":"240.624481ms","start":"2026-06-16T00:53:12.673551Z","end":"2026-06-16T00:53:12.914175Z","steps":["trace[452837114] 'process raft request' (duration: 240.323811ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:12.914246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.375384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:53:12.914281Z","caller":"traceutil/trace.go:171","msg":"trace[1656028134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1683; }","duration":"205.470437ms","start":"2026-06-16T00:53:12.7088Z","end":"2026-06-16T00:53:12.914271Z","steps":["trace[1656028134] 'agreement among raft nodes before linearized reading' (duration: 205.397645ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:53:18.279804Z","caller":"traceutil/trace.go:171","msg":"trace[681895715] linearizableReadLoop","detail":"{readStateIndex:1826; appliedIndex:1825; }","duration":"267.402519ms","start":"2026-06-16T00:53:18.012376Z","end":"2026-06-16T00:53:18.279778Z","steps":["trace[681895715] 'read index received' (duration: 267.259704ms)","trace[681895715] 'applied index is now lower than readState.Index' (duration: 142.145µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:53:18.279865Z","caller":"traceutil/trace.go:171","msg":"trace[1111748442] transaction","detail":"{read_only:false; response_revision:1749; number_of_response:1; }","duration":"268.348859ms","start":"2026-06-16T00:53:18.011477Z","end":"2026-06-16T00:53:18.279826Z","steps":["trace[1111748442] 'process raft request' (duration: 268.204745ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:18.280725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.360936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:53:18.280831Z","caller":"traceutil/trace.go:171","msg":"trace[880576163] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1749; }","duration":"248.50963ms","start":"2026-06-16T00:53:18.0323Z","end":"2026-06-16T00:53:18.280809Z","steps":["trace[880576163] 'agreement among raft nodes before linearized reading' (duration: 248.332635ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:18.280927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.517934ms","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-06-16T00:53:18.281056Z","caller":"traceutil/trace.go:171","msg":"trace[1126209549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1749; }","duration":"268.647679ms","start":"2026-06-16T00:53:18.012359Z","end":"2026-06-16T00:53:18.281007Z","steps":["trace[1126209549] 'agreement among raft nodes before linearized reading' (duration: 268.351509ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:18.281156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.596277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:53:18.281353Z","caller":"traceutil/trace.go:171","msg":"trace[1588255541] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1749; }","duration":"107.804534ms","start":"2026-06-16T00:53:18.173519Z","end":"2026-06-16T00:53:18.281323Z","steps":["trace[1588255541] 'agreement among raft nodes before linearized reading' (duration: 107.250526ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:18.281366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.020903ms","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:5"} {"level":"info","ts":"2026-06-16T00:53:18.281522Z","caller":"traceutil/trace.go:171","msg":"trace[1089036854] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1749; }","duration":"139.23237ms","start":"2026-06-16T00:53:18.142242Z","end":"2026-06-16T00:53:18.281474Z","steps":["trace[1089036854] 'agreement among raft nodes before linearized reading' (duration: 138.730204ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:18.281487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.029839ms","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:5"} {"level":"info","ts":"2026-06-16T00:53:18.281657Z","caller":"traceutil/trace.go:171","msg":"trace[124542448] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1749; }","duration":"190.227675ms","start":"2026-06-16T00:53:18.091345Z","end":"2026-06-16T00:53:18.281573Z","steps":["trace[124542448] 'agreement among raft nodes before linearized reading' (duration: 189.604805ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:18.281866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.731551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:53:18.281931Z","caller":"traceutil/trace.go:171","msg":"trace[1170243183] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1749; }","duration":"190.834004ms","start":"2026-06-16T00:53:18.091082Z","end":"2026-06-16T00:53:18.281916Z","steps":["trace[1170243183] 'agreement among raft nodes before linearized reading' (duration: 190.555045ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:18.282167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.920959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:53:18.282224Z","caller":"traceutil/trace.go:171","msg":"trace[203064187] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1749; }","duration":"222.003181ms","start":"2026-06-16T00:53:18.060195Z","end":"2026-06-16T00:53:18.282198Z","steps":["trace[203064187] 'agreement among raft nodes before linearized reading' (duration: 221.921159ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:18.282417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.069566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:53:18.282493Z","caller":"traceutil/trace.go:171","msg":"trace[407477300] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1749; }","duration":"140.166119ms","start":"2026-06-16T00:53:18.1423Z","end":"2026-06-16T00:53:18.282466Z","steps":["trace[407477300] 'agreement among raft nodes before linearized reading' (duration: 140.064006ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:18.282753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.56517ms","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:5"} {"level":"warn","ts":"2026-06-16T00:53:18.282793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.7752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:53:18.282808Z","caller":"traceutil/trace.go:171","msg":"trace[562953809] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1749; }","duration":"222.642852ms","start":"2026-06-16T00:53:18.060142Z","end":"2026-06-16T00:53:18.282785Z","steps":["trace[562953809] 'agreement among raft nodes before linearized reading' (duration: 222.547879ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:53:18.282829Z","caller":"traceutil/trace.go:171","msg":"trace[1215433787] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1749; }","duration":"165.826792ms","start":"2026-06-16T00:53:18.116984Z","end":"2026-06-16T00:53:18.282811Z","steps":["trace[1215433787] 'agreement among raft nodes before linearized reading' (duration: 165.77406ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:18.283075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.09995ms","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:5"} {"level":"info","ts":"2026-06-16T00:53:18.28316Z","caller":"traceutil/trace.go:171","msg":"trace[385802205] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1749; }","duration":"166.212974ms","start":"2026-06-16T00:53:18.116929Z","end":"2026-06-16T00:53:18.283142Z","steps":["trace[385802205] 'agreement among raft nodes before linearized reading' (duration: 166.027588ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:18.283593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.918361ms","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:5"} {"level":"info","ts":"2026-06-16T00:53:18.283621Z","caller":"traceutil/trace.go:171","msg":"trace[1048186427] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1749; }","duration":"109.965412ms","start":"2026-06-16T00:53:18.173648Z","end":"2026-06-16T00:53:18.283614Z","steps":["trace[1048186427] 'agreement among raft nodes before linearized reading' (duration: 109.90369ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:18.28505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.215168ms","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:5"} {"level":"info","ts":"2026-06-16T00:53:18.285125Z","caller":"traceutil/trace.go:171","msg":"trace[962493086] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1749; }","duration":"252.726194ms","start":"2026-06-16T00:53:18.03237Z","end":"2026-06-16T00:53:18.285096Z","steps":["trace[962493086] 'agreement among raft nodes before linearized reading' (duration: 250.594807ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:53:19.76522Z","caller":"traceutil/trace.go:171","msg":"trace[1355685958] transaction","detail":"{read_only:false; response_revision:1756; number_of_response:1; }","duration":"104.606898ms","start":"2026-06-16T00:53:19.660584Z","end":"2026-06-16T00:53:19.765191Z","steps":["trace[1355685958] 'process raft request' (duration: 96.947437ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:26.190832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.090141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:53:26.190905Z","caller":"traceutil/trace.go:171","msg":"trace[907756458] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1850; }","duration":"527.196374ms","start":"2026-06-16T00:53:25.663694Z","end":"2026-06-16T00:53:26.190891Z","steps":["trace[907756458] 'count revisions from in-memory index tree' (duration: 526.975777ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:26.190935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:53:25.663643Z","time spent":"527.284207ms","remote":"127.0.0.1:59126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-16T00:53:26.191057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.011649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T00:53:26.191116Z","caller":"traceutil/trace.go:171","msg":"trace[1531621716] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1850; }","duration":"415.106311ms","start":"2026-06-16T00:53:25.775996Z","end":"2026-06-16T00:53:26.191102Z","steps":["trace[1531621716] 'count revisions from in-memory index tree' (duration: 414.927646ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:26.191152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:53:25.775977Z","time spent":"415.164704ms","remote":"127.0.0.1:47386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":16,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-06-16T00:53:26.191096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.951174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T00:53:26.19121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.637629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a4993145-b65b-4cd0-b2d3-8e2c92df8763\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-06-16T00:53:26.191238Z","caller":"traceutil/trace.go:171","msg":"trace[2135193967] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a4993145-b65b-4cd0-b2d3-8e2c92df8763; range_end:; response_count:1; response_revision:1850; }","duration":"473.70016ms","start":"2026-06-16T00:53:25.717531Z","end":"2026-06-16T00:53:26.191231Z","steps":["trace[2135193967] 'range keys from in-memory index tree' (duration: 473.514685ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:26.191256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:53:25.717512Z","time spent":"473.739542ms","remote":"127.0.0.1:57816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a4993145-b65b-4cd0-b2d3-8e2c92df8763\" "} {"level":"info","ts":"2026-06-16T00:53:26.19129Z","caller":"traceutil/trace.go:171","msg":"trace[1101176340] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1850; }","duration":"482.14704ms","start":"2026-06-16T00:53:25.709074Z","end":"2026-06-16T00:53:26.191221Z","steps":["trace[1101176340] 'range keys from in-memory index tree' (duration: 481.839691ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:26.191373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:53:25.708974Z","time spent":"482.364587ms","remote":"127.0.0.1:57590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T00:53:26.191415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.005154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a4993145-b65b-4cd0-b2d3-8e2c92df8763\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-06-16T00:53:26.19148Z","caller":"traceutil/trace.go:171","msg":"trace[1684276191] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a4993145-b65b-4cd0-b2d3-8e2c92df8763; range_end:; response_count:1; response_revision:1850; }","duration":"693.109928ms","start":"2026-06-16T00:53:25.498356Z","end":"2026-06-16T00:53:26.191466Z","steps":["trace[1684276191] 'range keys from in-memory index tree' (duration: 692.892741ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:26.191512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:53:25.498335Z","time spent":"693.17136ms","remote":"127.0.0.1:57816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a4993145-b65b-4cd0-b2d3-8e2c92df8763\" "} {"level":"warn","ts":"2026-06-16T00:53:26.191414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.815753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-16T00:53:26.19155Z","caller":"traceutil/trace.go:171","msg":"trace[1289012256] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1850; }","duration":"698.993819ms","start":"2026-06-16T00:53:25.492542Z","end":"2026-06-16T00:53:26.191536Z","steps":["trace[1289012256] 'range keys from in-memory index tree' (duration: 698.600587ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:26.191596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:53:25.492521Z","time spent":"699.065181ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-16T00:53:26.192148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.253361ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T00:53:26.192258Z","caller":"traceutil/trace.go:171","msg":"trace[892975146] linearizableReadLoop","detail":"{readStateIndex:1930; appliedIndex:1929; }","duration":"389.00022ms","start":"2026-06-16T00:53:25.803209Z","end":"2026-06-16T00:53:26.19221Z","steps":["trace[892975146] 'read index received' (duration: 208.627506ms)","trace[892975146] 'applied index is now lower than readState.Index' (duration: 180.371424ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:53:26.192371Z","caller":"traceutil/trace.go:171","msg":"trace[884048954] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"410.752218ms","start":"2026-06-16T00:53:25.781606Z","end":"2026-06-16T00:53:26.192358Z","steps":["trace[884048954] 'process raft request' (duration: 230.220179ms)","trace[884048954] 'compare' (duration: 179.60165ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:53:26.19246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.332038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-16T00:53:26.192519Z","caller":"traceutil/trace.go:171","msg":"trace[730677494] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1851; }","duration":"288.4189ms","start":"2026-06-16T00:53:25.904087Z","end":"2026-06-16T00:53:26.192506Z","steps":["trace[730677494] 'agreement among raft nodes before linearized reading' (duration: 288.285086ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:26.192705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.495185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-06-16T00:53:26.192747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.873894ms","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-06-16T00:53:26.192786Z","caller":"traceutil/trace.go:171","msg":"trace[263376251] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1851; }","duration":"202.943677ms","start":"2026-06-16T00:53:25.98983Z","end":"2026-06-16T00:53:26.192774Z","steps":["trace[263376251] 'agreement among raft nodes before linearized reading' (duration: 202.849904ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:53:26.192747Z","caller":"traceutil/trace.go:171","msg":"trace[2110958988] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1851; }","duration":"389.597328ms","start":"2026-06-16T00:53:25.80314Z","end":"2026-06-16T00:53:26.192737Z","steps":["trace[2110958988] 'agreement among raft nodes before linearized reading' (duration: 389.364101ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:26.192836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:53:25.803121Z","time spent":"389.706412ms","remote":"127.0.0.1:35116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-16T00:53:26.192468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:53:25.781586Z","time spent":"410.81695ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:53:26.838786Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394066902337223,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-16T00:53:27.172402Z","caller":"traceutil/trace.go:171","msg":"trace[1149002087] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"974.337887ms","start":"2026-06-16T00:53:26.19804Z","end":"2026-06-16T00:53:27.172378Z","steps":["trace[1149002087] 'process raft request' (duration: 974.12911ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:27.172542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:53:26.198021Z","time spent":"974.448679ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:53:27.172654Z","caller":"traceutil/trace.go:171","msg":"trace[760609582] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"974.502481ms","start":"2026-06-16T00:53:26.198128Z","end":"2026-06-16T00:53:27.172631Z","steps":["trace[760609582] 'process raft request' (duration: 974.214882ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:53:27.172727Z","caller":"traceutil/trace.go:171","msg":"trace[1322273068] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"972.131099ms","start":"2026-06-16T00:53:26.20058Z","end":"2026-06-16T00:53:27.172711Z","steps":["trace[1322273068] 'process raft request' (duration: 971.992104ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:27.172797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:53:26.198117Z","time spent":"974.614464ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:53:27.172826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:53:26.200569Z","time spent":"972.20187ms","remote":"127.0.0.1:57816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3728,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:53:28.158243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:53:27.598105Z","time spent":"560.128208ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-16T00:53:28.159248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.069546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T00:53:28.159413Z","caller":"traceutil/trace.go:171","msg":"trace[1036557281] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"1.493161073s","start":"2026-06-16T00:53:26.666231Z","end":"2026-06-16T00:53:28.159392Z","steps":["trace[1036557281] 'process raft request' (duration: 1.350787188s)","trace[1036557281] 'compare' (duration: 141.954202ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:53:28.159481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:53:26.666203Z","time spent":"1.493247216s","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:53:28.159728Z","caller":"traceutil/trace.go:171","msg":"trace[1433987255] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"1.428761795s","start":"2026-06-16T00:53:26.730921Z","end":"2026-06-16T00:53:28.159683Z","steps":["trace[1433987255] 'process raft request' (duration: 1.428427225s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:28.159923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:53:26.730903Z","time spent":"1.42891585s","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:53:28.160402Z","caller":"traceutil/trace.go:171","msg":"trace[1035231206] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"1.424722822s","start":"2026-06-16T00:53:26.735646Z","end":"2026-06-16T00:53:28.160369Z","steps":["trace[1035231206] 'process raft request' (duration: 1.423933158s)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:53:28.16067Z","caller":"traceutil/trace.go:171","msg":"trace[856386370] linearizableReadLoop","detail":"{readStateIndex:1937; appliedIndex:1931; }","duration":"1.822593755s","start":"2026-06-16T00:53:26.338051Z","end":"2026-06-16T00:53:28.160645Z","steps":["trace[856386370] 'read index received' (duration: 834.073738ms)","trace[856386370] 'applied index is now lower than readState.Index' (duration: 988.519027ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:53:28.16071Z","caller":"traceutil/trace.go:171","msg":"trace[1924704027] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"1.019263263s","start":"2026-06-16T00:53:27.141425Z","end":"2026-06-16T00:53:28.160689Z","steps":["trace[1924704027] 'process raft request' (duration: 1.019109179s)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:53:28.160778Z","caller":"traceutil/trace.go:171","msg":"trace[1747130460] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"1.020583114s","start":"2026-06-16T00:53:27.140148Z","end":"2026-06-16T00:53:28.160731Z","steps":["trace[1747130460] 'process raft request' (duration: 1.01981664s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:28.16059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:53:26.735637Z","time spent":"1.424864316s","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:53:28.160875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:53:27.14013Z","time spent":"1.020701587s","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:53:28.162285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.822850712s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-16T00:53:28.162299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.805812922s","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-06-16T00:53:28.162337Z","caller":"traceutil/trace.go:171","msg":"trace[1398495198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1860; }","duration":"1.824288146s","start":"2026-06-16T00:53:26.338036Z","end":"2026-06-16T00:53:28.162324Z","steps":["trace[1398495198] 'agreement among raft nodes before linearized reading' (duration: 1.822745469s)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:53:28.162353Z","caller":"traceutil/trace.go:171","msg":"trace[262220511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1860; }","duration":"1.805931715s","start":"2026-06-16T00:53:26.356402Z","end":"2026-06-16T00:53:28.162334Z","steps":["trace[262220511] 'agreement among raft nodes before linearized reading' (duration: 1.804471111s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:28.162363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:53:27.141398Z","time spent":"1.01945985s","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:53:28.16277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.192585383s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T00:53:28.162807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.293095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-06-16T00:53:28.162822Z","caller":"traceutil/trace.go:171","msg":"trace[403660405] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1860; }","duration":"1.192673425s","start":"2026-06-16T00:53:26.970127Z","end":"2026-06-16T00:53:28.162801Z","steps":["trace[403660405] 'agreement among raft nodes before linearized reading' (duration: 1.192305474s)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:53:28.162845Z","caller":"traceutil/trace.go:171","msg":"trace[185844836] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1860; }","duration":"815.371247ms","start":"2026-06-16T00:53:27.347465Z","end":"2026-06-16T00:53:28.162836Z","steps":["trace[185844836] 'agreement among raft nodes before linearized reading' (duration: 814.941524ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:28.162859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:53:26.970099Z","time spent":"1.192751308s","remote":"127.0.0.1:47090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-16T00:53:28.162882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:53:27.347442Z","time spent":"815.43367ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-06-16T00:53:28.162387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:53:26.356378Z","time spent":"1.806002508s","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-16T00:53:28.162903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.507291245s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:53:28.162978Z","caller":"traceutil/trace.go:171","msg":"trace[1197645180] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1860; }","duration":"1.507405388s","start":"2026-06-16T00:53:26.655559Z","end":"2026-06-16T00:53:28.162964Z","steps":["trace[1197645180] 'agreement among raft nodes before linearized reading' (duration: 1.507257284s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:28.162388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:53:26.337989Z","time spent":"1.824384979s","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-16T00:53:28.163015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.679849482s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-06-16T00:53:28.163028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:53:26.655506Z","time spent":"1.507506011s","remote":"127.0.0.1:57842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-06-16T00:53:28.163045Z","caller":"traceutil/trace.go:171","msg":"trace[1837648723] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1860; }","duration":"1.679910883s","start":"2026-06-16T00:53:26.483125Z","end":"2026-06-16T00:53:28.163036Z","steps":["trace[1837648723] 'agreement among raft nodes before linearized reading' (duration: 1.679833711s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:28.163072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:53:26.483105Z","time spent":"1.679960264s","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-06-16T00:53:28.163126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.235097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-06-16T00:53:28.163163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048540134s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:53:28.163166Z","caller":"traceutil/trace.go:171","msg":"trace[1742059657] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1860; }","duration":"962.308079ms","start":"2026-06-16T00:53:27.200847Z","end":"2026-06-16T00:53:28.163155Z","steps":["trace[1742059657] 'agreement among raft nodes before linearized reading' (duration: 962.181645ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:28.163195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:53:27.200837Z","time spent":"962.3512ms","remote":"127.0.0.1:35116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-06-16T00:53:28.163192Z","caller":"traceutil/trace.go:171","msg":"trace[1407829647] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1860; }","duration":"1.048614716s","start":"2026-06-16T00:53:27.114568Z","end":"2026-06-16T00:53:28.163183Z","steps":["trace[1407829647] 'agreement among raft nodes before linearized reading' (duration: 1.048539404s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:28.163212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.453899033s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T00:53:28.16324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:53:27.114546Z","time spent":"1.048684608s","remote":"127.0.0.1:47254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-06-16T00:53:28.163246Z","caller":"traceutil/trace.go:171","msg":"trace[2098063757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1860; }","duration":"1.453947634s","start":"2026-06-16T00:53:26.709286Z","end":"2026-06-16T00:53:28.163233Z","steps":["trace[2098063757] 'agreement among raft nodes before linearized reading' (duration: 1.453901743s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:28.163274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:53:26.709273Z","time spent":"1.453995575s","remote":"127.0.0.1:57590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T00:53:28.163412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.26023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a4993145-b65b-4cd0-b2d3-8e2c92df8763\" ","response":"range_response_count:1 size:3743"} {"level":"info","ts":"2026-06-16T00:53:28.163459Z","caller":"traceutil/trace.go:171","msg":"trace[474498175] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a4993145-b65b-4cd0-b2d3-8e2c92df8763; range_end:; response_count:1; response_revision:1860; }","duration":"968.302832ms","start":"2026-06-16T00:53:27.195143Z","end":"2026-06-16T00:53:28.163446Z","steps":["trace[474498175] 'agreement among raft nodes before linearized reading' (duration: 968.183948ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:28.163505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:53:27.195124Z","time spent":"968.369903ms","remote":"127.0.0.1:57816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3766,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a4993145-b65b-4cd0-b2d3-8e2c92df8763\" "} {"level":"info","ts":"2026-06-16T00:53:29.763418Z","caller":"traceutil/trace.go:171","msg":"trace[777361830] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"209.887724ms","start":"2026-06-16T00:53:29.553499Z","end":"2026-06-16T00:53:29.763387Z","steps":["trace[777361830] 'process raft request' (duration: 189.487568ms)","trace[777361830] 'compare' (duration: 20.238141ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:53:29.804692Z","caller":"traceutil/trace.go:171","msg":"trace[39856974] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"249.970913ms","start":"2026-06-16T00:53:29.55467Z","end":"2026-06-16T00:53:29.804641Z","steps":["trace[39856974] 'process raft request' (duration: 249.719925ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:53:29.80488Z","caller":"traceutil/trace.go:171","msg":"trace[1144384406] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"250.170839ms","start":"2026-06-16T00:53:29.55468Z","end":"2026-06-16T00:53:29.80485Z","steps":["trace[1144384406] 'process raft request' (duration: 249.844809ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:53:29.805344Z","caller":"traceutil/trace.go:171","msg":"trace[1739220577] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"250.348704ms","start":"2026-06-16T00:53:29.554969Z","end":"2026-06-16T00:53:29.805317Z","steps":["trace[1739220577] 'process raft request' (duration: 249.607622ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:53:30.369033Z","caller":"traceutil/trace.go:171","msg":"trace[1588605735] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"198.767423ms","start":"2026-06-16T00:53:30.170243Z","end":"2026-06-16T00:53:30.36901Z","steps":["trace[1588605735] 'process raft request' (duration: 198.601978ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:53:30.369208Z","caller":"traceutil/trace.go:171","msg":"trace[1974359444] linearizableReadLoop","detail":"{readStateIndex:1962; appliedIndex:1959; }","duration":"192.662199ms","start":"2026-06-16T00:53:30.176522Z","end":"2026-06-16T00:53:30.369184Z","steps":["trace[1974359444] 'read index received' (duration: 28.574059ms)","trace[1974359444] 'applied index is now lower than readState.Index' (duration: 164.08669ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:53:30.369325Z","caller":"traceutil/trace.go:171","msg":"trace[437680470] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"197.603908ms","start":"2026-06-16T00:53:30.171673Z","end":"2026-06-16T00:53:30.369277Z","steps":["trace[437680470] 'process raft request' (duration: 197.298309ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:30.36948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.927957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-16T00:53:30.369569Z","caller":"traceutil/trace.go:171","msg":"trace[722961707] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1882; }","duration":"193.061761ms","start":"2026-06-16T00:53:30.176486Z","end":"2026-06-16T00:53:30.369548Z","steps":["trace[722961707] 'agreement among raft nodes before linearized reading' (duration: 192.822304ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:53:30.427581Z","caller":"traceutil/trace.go:171","msg":"trace[1279216904] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"177.590685ms","start":"2026-06-16T00:53:30.249962Z","end":"2026-06-16T00:53:30.427553Z","steps":["trace[1279216904] 'process raft request' (duration: 177.321497ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:30.427651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.742761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-16T00:53:30.427672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.462059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-16T00:53:30.427733Z","caller":"traceutil/trace.go:171","msg":"trace[201650102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1883; }","duration":"198.891235ms","start":"2026-06-16T00:53:30.228822Z","end":"2026-06-16T00:53:30.427713Z","steps":["trace[201650102] 'agreement among raft nodes before linearized reading' (duration: 198.695559ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:53:30.427737Z","caller":"traceutil/trace.go:171","msg":"trace[1238399668] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1883; }","duration":"129.581984ms","start":"2026-06-16T00:53:30.298141Z","end":"2026-06-16T00:53:30.427723Z","steps":["trace[1238399668] 'agreement among raft nodes before linearized reading' (duration: 129.303115ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:30.427877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.816784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-16T00:53:30.428019Z","caller":"traceutil/trace.go:171","msg":"trace[369389603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1883; }","duration":"199.008979ms","start":"2026-06-16T00:53:30.228999Z","end":"2026-06-16T00:53:30.428008Z","steps":["trace[369389603] 'agreement among raft nodes before linearized reading' (duration: 198.472813ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:30.428075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.076214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a4993145-b65b-4cd0-b2d3-8e2c92df8763\" ","response":"range_response_count:1 size:3929"} {"level":"info","ts":"2026-06-16T00:53:30.428178Z","caller":"traceutil/trace.go:171","msg":"trace[296781057] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a4993145-b65b-4cd0-b2d3-8e2c92df8763; range_end:; response_count:1; response_revision:1883; }","duration":"251.191887ms","start":"2026-06-16T00:53:30.176946Z","end":"2026-06-16T00:53:30.428138Z","steps":["trace[296781057] 'agreement among raft nodes before linearized reading' (duration: 250.475025ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:53:30.550219Z","caller":"traceutil/trace.go:171","msg":"trace[1198569388] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"115.47683ms","start":"2026-06-16T00:53:30.434721Z","end":"2026-06-16T00:53:30.550198Z","steps":["trace[1198569388] 'process raft request' (duration: 115.404837ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:53:30.55045Z","caller":"traceutil/trace.go:171","msg":"trace[752798872] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"117.510831ms","start":"2026-06-16T00:53:30.432927Z","end":"2026-06-16T00:53:30.550438Z","steps":["trace[752798872] 'process raft request' (duration: 44.498167ms)","trace[752798872] 'compare' (duration: 72.592891ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:53:30.550691Z","caller":"traceutil/trace.go:171","msg":"trace[1606536360] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"115.898193ms","start":"2026-06-16T00:53:30.434771Z","end":"2026-06-16T00:53:30.550669Z","steps":["trace[1606536360] 'process raft request' (duration: 115.396668ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:53:31.936238Z","caller":"traceutil/trace.go:171","msg":"trace[1986167314] linearizableReadLoop","detail":"{readStateIndex:1978; appliedIndex:1977; }","duration":"228.558258ms","start":"2026-06-16T00:53:31.70765Z","end":"2026-06-16T00:53:31.936209Z","steps":["trace[1986167314] 'read index received' (duration: 152.802681ms)","trace[1986167314] 'applied index is now lower than readState.Index' (duration: 75.754347ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:53:31.936787Z","caller":"traceutil/trace.go:171","msg":"trace[1663239175] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"238.359921ms","start":"2026-06-16T00:53:31.698406Z","end":"2026-06-16T00:53:31.936766Z","steps":["trace[1663239175] 'process raft request' (duration: 162.007487ms)","trace[1663239175] 'compare' (duration: 75.688154ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:53:31.936852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.151535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:53:31.937054Z","caller":"traceutil/trace.go:171","msg":"trace[1270533478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1898; }","duration":"229.369112ms","start":"2026-06-16T00:53:31.707599Z","end":"2026-06-16T00:53:31.936968Z","steps":["trace[1270533478] 'agreement among raft nodes before linearized reading' (duration: 229.083074ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:53:36.551185Z","caller":"traceutil/trace.go:171","msg":"trace[1797137053] transaction","detail":"{read_only:false; response_revision:1942; number_of_response:1; }","duration":"106.708625ms","start":"2026-06-16T00:53:36.444458Z","end":"2026-06-16T00:53:36.551166Z","steps":["trace[1797137053] 'process raft request' (duration: 106.281312ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:53:46.708868Z","caller":"traceutil/trace.go:171","msg":"trace[606277583] transaction","detail":"{read_only:false; response_revision:2010; number_of_response:1; }","duration":"112.521502ms","start":"2026-06-16T00:53:46.596318Z","end":"2026-06-16T00:53:46.70884Z","steps":["trace[606277583] 'process raft request' (duration: 112.233494ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:53:55.652535Z","caller":"traceutil/trace.go:171","msg":"trace[1377710267] transaction","detail":"{read_only:false; response_revision:2067; number_of_response:1; }","duration":"236.07488ms","start":"2026-06-16T00:53:55.416414Z","end":"2026-06-16T00:53:55.652488Z","steps":["trace[1377710267] 'process raft request' (duration: 235.821813ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:53:55.652397Z","caller":"traceutil/trace.go:171","msg":"trace[1197920371] linearizableReadLoop","detail":"{readStateIndex:2152; appliedIndex:2151; }","duration":"214.043473ms","start":"2026-06-16T00:53:55.438334Z","end":"2026-06-16T00:53:55.652377Z","steps":["trace[1197920371] 'read index received' (duration: 213.842218ms)","trace[1197920371] 'applied index is now lower than readState.Index' (duration: 200.705µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:53:55.65285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.502635ms","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-06-16T00:53:55.652902Z","caller":"traceutil/trace.go:171","msg":"trace[63508251] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2067; }","duration":"214.626039ms","start":"2026-06-16T00:53:55.438262Z","end":"2026-06-16T00:53:55.652888Z","steps":["trace[63508251] 'agreement among raft nodes before linearized reading' (duration: 214.370492ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:53:55.907078Z","caller":"traceutil/trace.go:171","msg":"trace[883018764] linearizableReadLoop","detail":"{readStateIndex:2153; appliedIndex:2152; }","duration":"159.086642ms","start":"2026-06-16T00:53:55.747959Z","end":"2026-06-16T00:53:55.907046Z","steps":["trace[883018764] 'read index received' (duration: 154.837658ms)","trace[883018764] 'applied index is now lower than readState.Index' (duration: 4.247734ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:53:55.907592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.603996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-06-16T00:53:55.907735Z","caller":"traceutil/trace.go:171","msg":"trace[297779186] transaction","detail":"{read_only:false; response_revision:2068; number_of_response:1; }","duration":"249.289148ms","start":"2026-06-16T00:53:55.657846Z","end":"2026-06-16T00:53:55.907135Z","steps":["trace[297779186] 'process raft request' (duration: 244.991292ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:53:55.90787Z","caller":"traceutil/trace.go:171","msg":"trace[1592827778] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2068; }","duration":"159.917705ms","start":"2026-06-16T00:53:55.74788Z","end":"2026-06-16T00:53:55.907797Z","steps":["trace[1592827778] 'agreement among raft nodes before linearized reading' (duration: 159.303118ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:53:55.908634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.650041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-16T00:53:55.908705Z","caller":"traceutil/trace.go:171","msg":"trace[1728835074] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2068; }","duration":"109.756965ms","start":"2026-06-16T00:53:55.798922Z","end":"2026-06-16T00:53:55.908678Z","steps":["trace[1728835074] 'agreement among raft nodes before linearized reading' (duration: 109.57233ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:54:01.812229Z","caller":"traceutil/trace.go:171","msg":"trace[1555008618] linearizableReadLoop","detail":"{readStateIndex:2186; appliedIndex:2185; }","duration":"101.298073ms","start":"2026-06-16T00:54:01.710908Z","end":"2026-06-16T00:54:01.812206Z","steps":["trace[1555008618] 'read index received' (duration: 101.087808ms)","trace[1555008618] 'applied index is now lower than readState.Index' (duration: 209.235µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:54:01.812297Z","caller":"traceutil/trace.go:171","msg":"trace[441062281] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"131.593016ms","start":"2026-06-16T00:54:01.680669Z","end":"2026-06-16T00:54:01.812262Z","steps":["trace[441062281] 'process raft request' (duration: 131.394141ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:54:01.812345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.423846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:54:01.81257Z","caller":"traceutil/trace.go:171","msg":"trace[779483026] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2100; }","duration":"101.697963ms","start":"2026-06-16T00:54:01.710853Z","end":"2026-06-16T00:54:01.812551Z","steps":["trace[779483026] 'agreement among raft nodes before linearized reading' (duration: 101.441627ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:54:02.799891Z","caller":"traceutil/trace.go:171","msg":"trace[854361282] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"231.934961ms","start":"2026-06-16T00:54:02.567929Z","end":"2026-06-16T00:54:02.799864Z","steps":["trace[854361282] 'process raft request' (duration: 231.798687ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:54:02.838306Z","caller":"traceutil/trace.go:171","msg":"trace[1452906945] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"250.167561ms","start":"2026-06-16T00:54:02.588123Z","end":"2026-06-16T00:54:02.838291Z","steps":["trace[1452906945] 'process raft request' (duration: 250.041648ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:54:03.678323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.469214ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T00:54:03.678527Z","caller":"traceutil/trace.go:171","msg":"trace[212258866] linearizableReadLoop","detail":"{readStateIndex:2194; appliedIndex:2192; }","duration":"717.233976ms","start":"2026-06-16T00:54:02.961274Z","end":"2026-06-16T00:54:03.678508Z","steps":["trace[212258866] 'read index received' (duration: 193.766466ms)","trace[212258866] 'applied index is now lower than readState.Index' (duration: 523.46442ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:54:03.679167Z","caller":"traceutil/trace.go:171","msg":"trace[683138201] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"810.59117ms","start":"2026-06-16T00:54:02.868556Z","end":"2026-06-16T00:54:03.679147Z","steps":["trace[683138201] 'process raft request' (duration: 73.197531ms)","trace[683138201] 'compare' (duration: 735.856498ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:54:03.679192Z","caller":"traceutil/trace.go:171","msg":"trace[418574827] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"810.328553ms","start":"2026-06-16T00:54:02.86883Z","end":"2026-06-16T00:54:03.679158Z","steps":["trace[418574827] 'process raft request' (duration: 809.618115ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:54:03.679247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:54:02.868533Z","time spent":"810.671491ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:54:03.679299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.023657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-16T00:54:03.679353Z","caller":"traceutil/trace.go:171","msg":"trace[1806406448] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2108; }","duration":"718.144581ms","start":"2026-06-16T00:54:02.961188Z","end":"2026-06-16T00:54:03.679333Z","steps":["trace[1806406448] 'agreement among raft nodes before linearized reading' (duration: 717.945266ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:54:03.679414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:54:02.961161Z","time spent":"718.237863ms","remote":"127.0.0.1:35116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-16T00:54:03.679479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.987082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-06-16T00:54:03.679574Z","caller":"traceutil/trace.go:171","msg":"trace[1113581900] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2108; }","duration":"371.117506ms","start":"2026-06-16T00:54:03.308432Z","end":"2026-06-16T00:54:03.679549Z","steps":["trace[1113581900] 'agreement among raft nodes before linearized reading' (duration: 370.90576ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:54:03.679633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:54:03.308374Z","time spent":"371.244099ms","remote":"127.0.0.1:57680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-06-16T00:54:03.679762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.497922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:120081"} {"level":"info","ts":"2026-06-16T00:54:03.679827Z","caller":"traceutil/trace.go:171","msg":"trace[2069573405] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2108; }","duration":"380.605414ms","start":"2026-06-16T00:54:03.299206Z","end":"2026-06-16T00:54:03.679811Z","steps":["trace[2069573405] 'agreement among raft nodes before linearized reading' (duration: 380.157103ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:54:03.679869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:54:03.299186Z","time spent":"380.671697ms","remote":"127.0.0.1:57668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":120104,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-06-16T00:54:03.680895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:54:02.868813Z","time spent":"810.465066ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:54:03.939723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.648871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-16T00:54:03.939824Z","caller":"traceutil/trace.go:171","msg":"trace[1159037911] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2108; }","duration":"123.761634ms","start":"2026-06-16T00:54:03.816041Z","end":"2026-06-16T00:54:03.939803Z","steps":["trace[1159037911] 'range keys from in-memory index tree' (duration: 123.398574ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:54:04.260284Z","caller":"traceutil/trace.go:171","msg":"trace[1868854299] linearizableReadLoop","detail":"{readStateIndex:2195; appliedIndex:2194; }","duration":"313.355251ms","start":"2026-06-16T00:54:03.946904Z","end":"2026-06-16T00:54:04.260259Z","steps":["trace[1868854299] 'read index received' (duration: 313.210968ms)","trace[1868854299] 'applied index is now lower than readState.Index' (duration: 143.343µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:54:04.260527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.612478ms","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-06-16T00:54:04.260799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.127156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b9699a4a2c0c7f\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-06-16T00:54:04.260818Z","caller":"traceutil/trace.go:171","msg":"trace[1717951588] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2109; }","duration":"313.940167ms","start":"2026-06-16T00:54:03.94686Z","end":"2026-06-16T00:54:04.2608Z","steps":["trace[1717951588] 'agreement among raft nodes before linearized reading' (duration: 313.467094ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:54:04.260302Z","caller":"traceutil/trace.go:171","msg":"trace[288935292] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"316.148715ms","start":"2026-06-16T00:54:03.944125Z","end":"2026-06-16T00:54:04.260274Z","steps":["trace[288935292] 'process raft request' (duration: 316.010831ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:54:04.260865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:54:03.946803Z","time spent":"314.052559ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-16T00:54:04.260869Z","caller":"traceutil/trace.go:171","msg":"trace[1365575329] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18b9699a4a2c0c7f; range_end:; response_count:1; response_revision:2109; }","duration":"267.248309ms","start":"2026-06-16T00:54:03.993607Z","end":"2026-06-16T00:54:04.260855Z","steps":["trace[1365575329] 'agreement among raft nodes before linearized reading' (duration: 266.817148ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:54:04.261022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:54:03.944102Z","time spent":"316.837283ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:54:33.052694Z","caller":"traceutil/trace.go:171","msg":"trace[770625210] transaction","detail":"{read_only:false; response_revision:2264; number_of_response:1; }","duration":"106.023118ms","start":"2026-06-16T00:54:32.946646Z","end":"2026-06-16T00:54:33.052669Z","steps":["trace[770625210] 'process raft request' (duration: 76.583243ms)","trace[770625210] 'compare' (duration: 28.832131ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:54:37.141716Z","caller":"traceutil/trace.go:171","msg":"trace[174174281] transaction","detail":"{read_only:false; response_revision:2298; number_of_response:1; }","duration":"147.640632ms","start":"2026-06-16T00:54:36.994049Z","end":"2026-06-16T00:54:37.14169Z","steps":["trace[174174281] 'process raft request' (duration: 104.381804ms)","trace[174174281] 'compare' (duration: 42.958191ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:55:00.237322Z","caller":"traceutil/trace.go:171","msg":"trace[635594678] transaction","detail":"{read_only:false; response_revision:2466; number_of_response:1; }","duration":"145.65448ms","start":"2026-06-16T00:55:00.091644Z","end":"2026-06-16T00:55:00.237299Z","steps":["trace[635594678] 'process raft request' (duration: 145.001126ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:55:08.984201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.129712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-06-16T00:55:08.984468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.798875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:55:08.984508Z","caller":"traceutil/trace.go:171","msg":"trace[1248018919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2561; }","duration":"273.873947ms","start":"2026-06-16T00:55:08.710627Z","end":"2026-06-16T00:55:08.984501Z","steps":["trace[1248018919] 'range keys from in-memory index tree' (duration: 273.726054ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:55:08.984267Z","caller":"traceutil/trace.go:171","msg":"trace[1029087783] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2561; }","duration":"274.242614ms","start":"2026-06-16T00:55:08.710007Z","end":"2026-06-16T00:55:08.98425Z","steps":["trace[1029087783] 'range keys from in-memory index tree' (duration: 273.945529ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:55:08.985034Z","caller":"traceutil/trace.go:171","msg":"trace[1899393695] linearizableReadLoop","detail":"{readStateIndex:2663; appliedIndex:2662; }","duration":"256.722413ms","start":"2026-06-16T00:55:08.728281Z","end":"2026-06-16T00:55:08.985003Z","steps":["trace[1899393695] 'read index received' (duration: 235.463253ms)","trace[1899393695] 'applied index is now lower than readState.Index' (duration: 21.25734ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:55:08.985242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.908697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T00:55:08.985217Z","caller":"traceutil/trace.go:171","msg":"trace[535084365] transaction","detail":"{read_only:false; response_revision:2562; number_of_response:1; }","duration":"263.347853ms","start":"2026-06-16T00:55:08.721836Z","end":"2026-06-16T00:55:08.985183Z","steps":["trace[535084365] 'process raft request' (duration: 241.95643ms)","trace[535084365] 'compare' (duration: 20.054025ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:55:08.985281Z","caller":"traceutil/trace.go:171","msg":"trace[1059959047] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:2562; }","duration":"257.02116ms","start":"2026-06-16T00:55:08.72825Z","end":"2026-06-16T00:55:08.985271Z","steps":["trace[1059959047] 'agreement among raft nodes before linearized reading' (duration: 256.865317ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:55:41.597301Z","caller":"traceutil/trace.go:171","msg":"trace[696461670] transaction","detail":"{read_only:false; response_revision:2741; number_of_response:1; }","duration":"220.324358ms","start":"2026-06-16T00:55:41.376952Z","end":"2026-06-16T00:55:41.597276Z","steps":["trace[696461670] 'process raft request' (duration: 220.173995ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:55:41.608158Z","caller":"traceutil/trace.go:171","msg":"trace[446034288] transaction","detail":"{read_only:false; response_revision:2742; number_of_response:1; }","duration":"206.212513ms","start":"2026-06-16T00:55:41.401931Z","end":"2026-06-16T00:55:41.608144Z","steps":["trace[446034288] 'process raft request' (duration: 205.977758ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:55:41.608571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.959755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-16T00:55:41.608619Z","caller":"traceutil/trace.go:171","msg":"trace[1480033326] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2742; }","duration":"137.066917ms","start":"2026-06-16T00:55:41.471543Z","end":"2026-06-16T00:55:41.60861Z","steps":["trace[1480033326] 'agreement among raft nodes before linearized reading' (duration: 136.934204ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:55:41.608001Z","caller":"traceutil/trace.go:171","msg":"trace[1222280070] linearizableReadLoop","detail":"{readStateIndex:2851; appliedIndex:2850; }","duration":"136.371084ms","start":"2026-06-16T00:55:41.471616Z","end":"2026-06-16T00:55:41.607987Z","steps":["trace[1222280070] 'read index received' (duration: 126.775887ms)","trace[1222280070] 'applied index is now lower than readState.Index' (duration: 9.594567ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:55:42.211349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.927527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:55:42.211436Z","caller":"traceutil/trace.go:171","msg":"trace[435271599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2743; }","duration":"502.022419ms","start":"2026-06-16T00:55:41.709396Z","end":"2026-06-16T00:55:42.211418Z","steps":["trace[435271599] 'range keys from in-memory index tree' (duration: 501.779215ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:55:42.211474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:55:41.709346Z","time spent":"502.120181ms","remote":"127.0.0.1:57590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T00:55:42.212127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.68294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T00:55:42.212174Z","caller":"traceutil/trace.go:171","msg":"trace[633615456] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:2743; }","duration":"414.731651ms","start":"2026-06-16T00:55:41.797429Z","end":"2026-06-16T00:55:42.21216Z","steps":["trace[633615456] 'count revisions from in-memory index tree' (duration: 414.608628ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:55:42.212209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:55:41.79735Z","time spent":"414.847803ms","remote":"127.0.0.1:57706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":24,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-06-16T00:55:42.213609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.752568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18b969ad9da58618\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-06-16T00:55:42.213698Z","caller":"traceutil/trace.go:171","msg":"trace[1373791423] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18b969ad9da58618; range_end:; response_count:1; response_revision:2743; }","duration":"215.882691ms","start":"2026-06-16T00:55:41.997799Z","end":"2026-06-16T00:55:42.213681Z","steps":["trace[1373791423] 'range keys from in-memory index tree' (duration: 215.525184ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:55:42.25574Z","caller":"traceutil/trace.go:171","msg":"trace[1236941571] transaction","detail":"{read_only:false; response_revision:2744; number_of_response:1; }","duration":"127.507466ms","start":"2026-06-16T00:55:42.128196Z","end":"2026-06-16T00:55:42.255703Z","steps":["trace[1236941571] 'process raft request' (duration: 127.280951ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:55:42.30679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.469357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-16T00:55:42.306848Z","caller":"traceutil/trace.go:171","msg":"trace[778444110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2745; }","duration":"122.5715ms","start":"2026-06-16T00:55:42.184263Z","end":"2026-06-16T00:55:42.306835Z","steps":["trace[778444110] 'agreement among raft nodes before linearized reading' (duration: 122.426657ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:55:47.292468Z","caller":"traceutil/trace.go:171","msg":"trace[2007108127] transaction","detail":"{read_only:false; response_revision:2774; number_of_response:1; }","duration":"172.209794ms","start":"2026-06-16T00:55:47.120236Z","end":"2026-06-16T00:55:47.292446Z","steps":["trace[2007108127] 'process raft request' (duration: 167.395102ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:55:47.309073Z","caller":"traceutil/trace.go:171","msg":"trace[970185531] transaction","detail":"{read_only:false; response_revision:2775; number_of_response:1; }","duration":"128.973205ms","start":"2026-06-16T00:55:47.180083Z","end":"2026-06-16T00:55:47.309056Z","steps":["trace[970185531] 'process raft request' (duration: 128.850232ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:58:43.566373Z","caller":"traceutil/trace.go:171","msg":"trace[1571582947] transaction","detail":"{read_only:false; response_revision:3860; number_of_response:1; }","duration":"100.933301ms","start":"2026-06-16T00:58:43.465403Z","end":"2026-06-16T00:58:43.566336Z","steps":["trace[1571582947] 'process raft request' (duration: 76.462525ms)","trace[1571582947] 'compare' (duration: 23.979409ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:59:22.228376Z","caller":"traceutil/trace.go:171","msg":"trace[1690161900] transaction","detail":"{read_only:false; response_revision:4364; number_of_response:1; }","duration":"187.376806ms","start":"2026-06-16T00:59:22.040977Z","end":"2026-06-16T00:59:22.228353Z","steps":["trace[1690161900] 'process raft request' (duration: 187.267635ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:59:22.232969Z","caller":"traceutil/trace.go:171","msg":"trace[1892559779] transaction","detail":"{read_only:false; response_revision:4365; number_of_response:1; }","duration":"177.260398ms","start":"2026-06-16T00:59:22.05568Z","end":"2026-06-16T00:59:22.23294Z","steps":["trace[1892559779] 'process raft request' (duration: 176.937154ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:59:22.233085Z","caller":"traceutil/trace.go:171","msg":"trace[336266501] transaction","detail":"{read_only:false; response_revision:4366; number_of_response:1; }","duration":"176.01973ms","start":"2026-06-16T00:59:22.057025Z","end":"2026-06-16T00:59:22.233045Z","steps":["trace[336266501] 'process raft request' (duration: 175.695745ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:59:22.233203Z","caller":"traceutil/trace.go:171","msg":"trace[1744271922] linearizableReadLoop","detail":"{readStateIndex:4543; appliedIndex:4540; }","duration":"172.070813ms","start":"2026-06-16T00:59:22.061117Z","end":"2026-06-16T00:59:22.233187Z","steps":["trace[1744271922] 'read index received' (duration: 167.10885ms)","trace[1744271922] 'applied index is now lower than readState.Index' (duration: 4.961273ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:59:22.233094Z","caller":"traceutil/trace.go:171","msg":"trace[1191207559] transaction","detail":"{read_only:false; response_revision:4367; number_of_response:1; }","duration":"157.668262ms","start":"2026-06-16T00:59:22.075395Z","end":"2026-06-16T00:59:22.233063Z","steps":["trace[1191207559] 'process raft request' (duration: 157.437979ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:59:22.23336Z","caller":"traceutil/trace.go:171","msg":"trace[1998032625] transaction","detail":"{read_only:false; response_revision:4368; number_of_response:1; }","duration":"137.716792ms","start":"2026-06-16T00:59:22.095637Z","end":"2026-06-16T00:59:22.233354Z","steps":["trace[1998032625] 'process raft request' (duration: 137.385417ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:59:22.233485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.242021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T00:59:22.233513Z","caller":"traceutil/trace.go:171","msg":"trace[706910130] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4368; }","duration":"158.315162ms","start":"2026-06-16T00:59:22.075191Z","end":"2026-06-16T00:59:22.233506Z","steps":["trace[706910130] 'agreement among raft nodes before linearized reading' (duration: 158.234731ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:59:22.233569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.439498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/grafana-tls\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:59:22.233592Z","caller":"traceutil/trace.go:171","msg":"trace[851114568] range","detail":"{range_begin:/registry/secrets/monitoring/grafana-tls; range_end:; response_count:0; response_revision:4368; }","duration":"172.503519ms","start":"2026-06-16T00:59:22.061083Z","end":"2026-06-16T00:59:22.233586Z","steps":["trace[851114568] 'agreement among raft nodes before linearized reading' (duration: 172.442638ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:59:23.73435Z","caller":"traceutil/trace.go:171","msg":"trace[635509200] transaction","detail":"{read_only:false; response_revision:4421; number_of_response:1; }","duration":"633.256621ms","start":"2026-06-16T00:59:23.101062Z","end":"2026-06-16T00:59:23.734318Z","steps":["trace[635509200] 'process raft request' (duration: 628.894738ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:59:23.734491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:59:23.101053Z","time spent":"633.372224ms","remote":"127.0.0.1:57816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4316,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:59:25.933007Z","caller":"traceutil/trace.go:171","msg":"trace[2125363779] transaction","detail":"{read_only:false; response_revision:4509; number_of_response:1; }","duration":"122.652398ms","start":"2026-06-16T00:59:25.810333Z","end":"2026-06-16T00:59:25.932985Z","steps":["trace[2125363779] 'process raft request' (duration: 121.342049ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:59:32.705922Z","caller":"traceutil/trace.go:171","msg":"trace[1863459909] transaction","detail":"{read_only:false; response_revision:4675; number_of_response:1; }","duration":"160.661534ms","start":"2026-06-16T00:59:32.545228Z","end":"2026-06-16T00:59:32.705889Z","steps":["trace[1863459909] 'process raft request' (duration: 160.486752ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:59:35.1794Z","caller":"traceutil/trace.go:171","msg":"trace[1654617286] linearizableReadLoop","detail":"{readStateIndex:4905; appliedIndex:4904; }","duration":"186.964992ms","start":"2026-06-16T00:59:34.992409Z","end":"2026-06-16T00:59:35.179374Z","steps":["trace[1654617286] 'read index received' (duration: 186.753909ms)","trace[1654617286] 'applied index is now lower than readState.Index' (duration: 210.173µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:59:35.179486Z","caller":"traceutil/trace.go:171","msg":"trace[1683695327] transaction","detail":"{read_only:false; response_revision:4719; number_of_response:1; }","duration":"321.000476ms","start":"2026-06-16T00:59:34.858459Z","end":"2026-06-16T00:59:35.179459Z","steps":["trace[1683695327] 'process raft request' (duration: 320.772443ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:59:35.179619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:59:34.858441Z","time spent":"321.115628ms","remote":"127.0.0.1:57816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9667,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:59:35.179675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.236016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-16T00:59:35.179764Z","caller":"traceutil/trace.go:171","msg":"trace[1227234650] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:4719; }","duration":"187.381727ms","start":"2026-06-16T00:59:34.992363Z","end":"2026-06-16T00:59:35.179745Z","steps":["trace[1227234650] 'agreement among raft nodes before linearized reading' (duration: 187.130104ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:59:37.538126Z","caller":"traceutil/trace.go:171","msg":"trace[779729513] transaction","detail":"{read_only:false; response_revision:4738; number_of_response:1; }","duration":"134.499769ms","start":"2026-06-16T00:59:37.403592Z","end":"2026-06-16T00:59:37.538092Z","steps":["trace[779729513] 'process raft request' (duration: 134.309286ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:59:37.54157Z","caller":"traceutil/trace.go:171","msg":"trace[1295307199] transaction","detail":"{read_only:false; response_revision:4739; number_of_response:1; }","duration":"136.593899ms","start":"2026-06-16T00:59:37.404944Z","end":"2026-06-16T00:59:37.541538Z","steps":["trace[1295307199] 'process raft request' (duration: 136.422206ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:59:37.792517Z","caller":"traceutil/trace.go:171","msg":"trace[724586151] transaction","detail":"{read_only:false; response_revision:4761; number_of_response:1; }","duration":"163.674491ms","start":"2026-06-16T00:59:37.628814Z","end":"2026-06-16T00:59:37.792488Z","steps":["trace[724586151] 'process raft request' (duration: 163.548399ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:59:37.792669Z","caller":"traceutil/trace.go:171","msg":"trace[784705108] linearizableReadLoop","detail":"{readStateIndex:4949; appliedIndex:4949; }","duration":"156.299304ms","start":"2026-06-16T00:59:37.636282Z","end":"2026-06-16T00:59:37.792581Z","steps":["trace[784705108] 'read index received' (duration: 156.284994ms)","trace[784705108] 'applied index is now lower than readState.Index' (duration: 11.54µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:59:37.792844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.555028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-06-16T00:59:37.792896Z","caller":"traceutil/trace.go:171","msg":"trace[1190234320] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4761; }","duration":"156.64978ms","start":"2026-06-16T00:59:37.636235Z","end":"2026-06-16T00:59:37.792885Z","steps":["trace[1190234320] 'agreement among raft nodes before linearized reading' (duration: 156.487217ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:59:38.876181Z","caller":"traceutil/trace.go:171","msg":"trace[1423618204] transaction","detail":"{read_only:false; response_revision:4802; number_of_response:1; }","duration":"120.513665ms","start":"2026-06-16T00:59:38.755633Z","end":"2026-06-16T00:59:38.876147Z","steps":["trace[1423618204] 'process raft request' (duration: 120.329983ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:59:41.100295Z","caller":"traceutil/trace.go:171","msg":"trace[1172806147] transaction","detail":"{read_only:false; response_revision:4815; number_of_response:1; }","duration":"137.59687ms","start":"2026-06-16T00:59:40.962663Z","end":"2026-06-16T00:59:41.10026Z","steps":["trace[1172806147] 'process raft request' (duration: 137.420177ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:59:42.992775Z","caller":"traceutil/trace.go:171","msg":"trace[1598031920] transaction","detail":"{read_only:false; response_revision:4839; number_of_response:1; }","duration":"104.801225ms","start":"2026-06-16T00:59:42.887457Z","end":"2026-06-16T00:59:42.992258Z","steps":["trace[1598031920] 'process raft request' (duration: 103.76069ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:59:56.827768Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394066902344709,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-16T00:59:57.243818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.906279ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T00:59:57.244061Z","caller":"traceutil/trace.go:171","msg":"trace[1973462207] linearizableReadLoop","detail":"{readStateIndex:5206; appliedIndex:5201; }","duration":"916.63462ms","start":"2026-06-16T00:59:56.32741Z","end":"2026-06-16T00:59:57.244045Z","steps":["trace[1973462207] 'read index received' (duration: 716.483227ms)","trace[1973462207] 'applied index is now lower than readState.Index' (duration: 200.149843ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:59:57.244124Z","caller":"traceutil/trace.go:171","msg":"trace[1079836080] transaction","detail":"{read_only:false; response_revision:5006; number_of_response:1; }","duration":"994.978405ms","start":"2026-06-16T00:59:56.249136Z","end":"2026-06-16T00:59:57.244115Z","steps":["trace[1079836080] 'process raft request' (duration: 794.69704ms)","trace[1079836080] 'compare' (duration: 199.605255ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:59:57.244167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:59:56.249113Z","time spent":"995.029086ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:59:57.244319Z","caller":"traceutil/trace.go:171","msg":"trace[573508901] transaction","detail":"{read_only:false; response_revision:5007; number_of_response:1; }","duration":"751.254596ms","start":"2026-06-16T00:59:56.493057Z","end":"2026-06-16T00:59:57.244311Z","steps":["trace[573508901] 'process raft request' (duration: 750.8503ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:59:57.244362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:59:56.493029Z","time spent":"751.309786ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:59:57.244468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.069017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:59:57.244434Z","caller":"traceutil/trace.go:171","msg":"trace[1712595295] transaction","detail":"{read_only:false; response_revision:5010; number_of_response:1; }","duration":"508.290137ms","start":"2026-06-16T00:59:56.736111Z","end":"2026-06-16T00:59:57.244401Z","steps":["trace[1712595295] 'process raft request' (duration: 507.904882ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:59:57.244495Z","caller":"traceutil/trace.go:171","msg":"trace[1554786596] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:5010; }","duration":"917.126737ms","start":"2026-06-16T00:59:56.32736Z","end":"2026-06-16T00:59:57.244487Z","steps":["trace[1554786596] 'agreement among raft nodes before linearized reading' (duration: 917.078607ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:59:57.244517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:59:56.327341Z","time spent":"917.170888ms","remote":"127.0.0.1:35124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true "} {"level":"warn","ts":"2026-06-16T00:59:57.244582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:59:56.736088Z","time spent":"508.419619ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:59:57.244728Z","caller":"traceutil/trace.go:171","msg":"trace[107996716] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"721.040742ms","start":"2026-06-16T00:59:56.523677Z","end":"2026-06-16T00:59:57.244717Z","steps":["trace[107996716] 'process raft request' (duration: 720.279261ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:59:57.244784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:59:56.523645Z","time spent":"721.108943ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:59:57.244877Z","caller":"traceutil/trace.go:171","msg":"trace[426734221] transaction","detail":"{read_only:false; response_revision:5009; number_of_response:1; }","duration":"715.739826ms","start":"2026-06-16T00:59:56.529129Z","end":"2026-06-16T00:59:57.244869Z","steps":["trace[426734221] 'process raft request' (duration: 714.861363ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:59:57.244926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:59:56.529116Z","time spent":"715.785756ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:59:57.45242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.416733ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T00:59:57.4525Z","caller":"traceutil/trace.go:171","msg":"trace[1424735951] linearizableReadLoop","detail":"{readStateIndex:5207; appliedIndex:5206; }","duration":"208.40245ms","start":"2026-06-16T00:59:57.244086Z","end":"2026-06-16T00:59:57.452488Z","steps":["trace[1424735951] 'read index received' (duration: 102.776125ms)","trace[1424735951] 'applied index is now lower than readState.Index' (duration: 105.625245ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:59:57.452863Z","caller":"traceutil/trace.go:171","msg":"trace[1416778212] transaction","detail":"{read_only:false; response_revision:5011; number_of_response:1; }","duration":"385.490702ms","start":"2026-06-16T00:59:57.067352Z","end":"2026-06-16T00:59:57.452842Z","steps":["trace[1416778212] 'process raft request' (duration: 279.581673ms)","trace[1416778212] 'compare' (duration: 105.227099ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:59:57.452909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.340567ms","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-06-16T00:59:57.452886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.905782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:59:57.452937Z","caller":"traceutil/trace.go:171","msg":"trace[415563586] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5011; }","duration":"281.407508ms","start":"2026-06-16T00:59:57.171523Z","end":"2026-06-16T00:59:57.45293Z","steps":["trace[415563586] 'agreement among raft nodes before linearized reading' (duration: 281.303627ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:59:57.452904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.094537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-06-16T00:59:57.452961Z","caller":"traceutil/trace.go:171","msg":"trace[849765670] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5011; }","duration":"745.041474ms","start":"2026-06-16T00:59:56.7079Z","end":"2026-06-16T00:59:57.452942Z","steps":["trace[849765670] 'agreement among raft nodes before linearized reading' (duration: 744.903502ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:59:57.452956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:59:57.067321Z","time spent":"385.590953ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:59:57.453003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:59:56.707882Z","time spent":"745.112575ms","remote":"127.0.0.1:57590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-16T00:59:57.453032Z","caller":"traceutil/trace.go:171","msg":"trace[227601275] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5011; }","duration":"137.226919ms","start":"2026-06-16T00:59:57.315759Z","end":"2026-06-16T00:59:57.452986Z","steps":["trace[227601275] 'agreement among raft nodes before linearized reading' (duration: 136.940395ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:59:57.452719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.842582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:59:57.453114Z","caller":"traceutil/trace.go:171","msg":"trace[1983355855] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:5011; }","duration":"729.276788ms","start":"2026-06-16T00:59:56.723827Z","end":"2026-06-16T00:59:57.453103Z","steps":["trace[1983355855] 'agreement among raft nodes before linearized reading' (duration: 728.819251ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:59:57.453151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:59:56.72381Z","time spent":"729.331538ms","remote":"127.0.0.1:35928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-06-16T00:59:57.453019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.599724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T00:59:57.453276Z","caller":"traceutil/trace.go:171","msg":"trace[1878605676] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:5011; }","duration":"115.01492ms","start":"2026-06-16T00:59:57.338241Z","end":"2026-06-16T00:59:57.453256Z","steps":["trace[1878605676] 'agreement among raft nodes before linearized reading' (duration: 114.592724ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:59:57.453574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.745149ms","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":"info","ts":"2026-06-16T00:59:57.453819Z","caller":"traceutil/trace.go:171","msg":"trace[1445355116] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5011; }","duration":"372.953542ms","start":"2026-06-16T00:59:57.080724Z","end":"2026-06-16T00:59:57.453677Z","steps":["trace[1445355116] 'agreement among raft nodes before linearized reading' (duration: 372.292152ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:59:57.453972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:59:57.080695Z","time spent":"373.187725ms","remote":"127.0.0.1:57776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-06-16T00:59:58.227377Z","caller":"traceutil/trace.go:171","msg":"trace[726297023] transaction","detail":"{read_only:false; response_revision:5016; number_of_response:1; }","duration":"105.028697ms","start":"2026-06-16T00:59:58.122318Z","end":"2026-06-16T00:59:58.227347Z","steps":["trace[726297023] 'process raft request' (duration: 104.836805ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:00:49.514068Z","caller":"traceutil/trace.go:171","msg":"trace[728044586] transaction","detail":"{read_only:false; response_revision:5455; number_of_response:1; }","duration":"102.325126ms","start":"2026-06-16T01:00:49.411723Z","end":"2026-06-16T01:00:49.514049Z","steps":["trace[728044586] 'process raft request' (duration: 102.183301ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:00:53.806198Z","caller":"traceutil/trace.go:171","msg":"trace[519734937] linearizableReadLoop","detail":"{readStateIndex:5694; appliedIndex:5691; }","duration":"133.301133ms","start":"2026-06-16T01:00:53.672879Z","end":"2026-06-16T01:00:53.80618Z","steps":["trace[519734937] 'read index received' (duration: 1.723668ms)","trace[519734937] 'applied index is now lower than readState.Index' (duration: 131.576745ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:00:53.806231Z","caller":"traceutil/trace.go:171","msg":"trace[689953556] transaction","detail":"{read_only:false; response_revision:5484; number_of_response:1; }","duration":"224.154398ms","start":"2026-06-16T01:00:53.582054Z","end":"2026-06-16T01:00:53.806209Z","steps":["trace[689953556] 'process raft request' (duration: 200.86363ms)","trace[689953556] 'compare' (duration: 23.100631ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:00:53.806406Z","caller":"traceutil/trace.go:171","msg":"trace[1902462718] transaction","detail":"{read_only:false; response_revision:5485; number_of_response:1; }","duration":"210.487371ms","start":"2026-06-16T01:00:53.595896Z","end":"2026-06-16T01:00:53.806383Z","steps":["trace[1902462718] 'process raft request' (duration: 210.232632ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:00:53.807025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.12486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-16T01:00:53.807119Z","caller":"traceutil/trace.go:171","msg":"trace[391247419] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5485; }","duration":"134.270955ms","start":"2026-06-16T01:00:53.672824Z","end":"2026-06-16T01:00:53.807095Z","steps":["trace[391247419] 'agreement among raft nodes before linearized reading' (duration: 133.718627ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:01:02.051764Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2853} {"level":"info","ts":"2026-06-16T01:01:02.144188Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2853,"took":"90.931742ms","hash":3653804627,"current-db-size-bytes":53751808,"current-db-size":"54 MB","current-db-size-in-use-bytes":28909568,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-16T01:01:02.144343Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3653804627,"revision":2853,"compact-revision":-1} {"level":"info","ts":"2026-06-16T01:01:26.877491Z","caller":"traceutil/trace.go:171","msg":"trace[95075947] linearizableReadLoop","detail":"{readStateIndex:5951; appliedIndex:5949; }","duration":"175.47023ms","start":"2026-06-16T01:01:26.701995Z","end":"2026-06-16T01:01:26.877466Z","steps":["trace[95075947] 'read index received' (duration: 55.277439ms)","trace[95075947] 'applied index is now lower than readState.Index' (duration: 120.191851ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:01:26.877586Z","caller":"traceutil/trace.go:171","msg":"trace[985366209] transaction","detail":"{read_only:false; response_revision:5734; number_of_response:1; }","duration":"175.624425ms","start":"2026-06-16T01:01:26.70191Z","end":"2026-06-16T01:01:26.877534Z","steps":["trace[985366209] 'process raft request' (duration: 174.394866ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:01:26.877735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.708097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-16T01:01:26.87778Z","caller":"traceutil/trace.go:171","msg":"trace[1129625433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5734; }","duration":"175.818091ms","start":"2026-06-16T01:01:26.701951Z","end":"2026-06-16T01:01:26.877769Z","steps":["trace[1129625433] 'agreement among raft nodes before linearized reading' (duration: 175.651216ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:01:26.937216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.949198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T01:01:26.937235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.450062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-16T01:01:26.93726Z","caller":"traceutil/trace.go:171","msg":"trace[991119070] transaction","detail":"{read_only:false; response_revision:5735; number_of_response:1; }","duration":"169.626556ms","start":"2026-06-16T01:01:26.767593Z","end":"2026-06-16T01:01:26.93722Z","steps":["trace[991119070] 'process raft request' (duration: 169.321557ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:01:26.937302Z","caller":"traceutil/trace.go:171","msg":"trace[2085891591] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5735; }","duration":"226.076762ms","start":"2026-06-16T01:01:26.711204Z","end":"2026-06-16T01:01:26.937281Z","steps":["trace[2085891591] 'agreement among raft nodes before linearized reading' (duration: 225.915647ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:01:26.93722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.993883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-06-16T01:01:26.937424Z","caller":"traceutil/trace.go:171","msg":"trace[1873895203] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5735; }","duration":"221.21803ms","start":"2026-06-16T01:01:26.716187Z","end":"2026-06-16T01:01:26.937405Z","steps":["trace[1873895203] 'agreement among raft nodes before linearized reading' (duration: 220.88513ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:01:26.937311Z","caller":"traceutil/trace.go:171","msg":"trace[1643055248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5735; }","duration":"170.569385ms","start":"2026-06-16T01:01:26.766723Z","end":"2026-06-16T01:01:26.937293Z","steps":["trace[1643055248] 'agreement among raft nodes before linearized reading' (duration: 170.352669ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:01:27.060729Z","caller":"traceutil/trace.go:171","msg":"trace[67100465] transaction","detail":"{read_only:false; response_revision:5737; number_of_response:1; }","duration":"114.429806ms","start":"2026-06-16T01:01:26.946273Z","end":"2026-06-16T01:01:27.060702Z","steps":["trace[67100465] 'process raft request' (duration: 114.146687ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:01:27.060813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.633685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T01:01:27.060993Z","caller":"traceutil/trace.go:171","msg":"trace[520356532] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5738; }","duration":"116.812331ms","start":"2026-06-16T01:01:26.944122Z","end":"2026-06-16T01:01:27.060935Z","steps":["trace[520356532] 'agreement among raft nodes before linearized reading' (duration: 116.588114ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:01:27.061188Z","caller":"traceutil/trace.go:171","msg":"trace[653363536] transaction","detail":"{read_only:false; response_revision:5738; number_of_response:1; }","duration":"114.401416ms","start":"2026-06-16T01:01:26.946776Z","end":"2026-06-16T01:01:27.061177Z","steps":["trace[653363536] 'process raft request' (duration: 113.873549ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:01:27.061485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.175022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-06-16T01:01:27.06155Z","caller":"traceutil/trace.go:171","msg":"trace[1269621021] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5738; }","duration":"117.226884ms","start":"2026-06-16T01:01:26.944286Z","end":"2026-06-16T01:01:27.061513Z","steps":["trace[1269621021] 'agreement among raft nodes before linearized reading' (duration: 117.08207ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:03:05.647636Z","caller":"traceutil/trace.go:171","msg":"trace[792161901] transaction","detail":"{read_only:false; response_revision:6290; number_of_response:1; }","duration":"132.145653ms","start":"2026-06-16T01:03:05.515463Z","end":"2026-06-16T01:03:05.647609Z","steps":["trace[792161901] 'process raft request' (duration: 131.888846ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:03:09.567015Z","caller":"traceutil/trace.go:171","msg":"trace[1529415416] transaction","detail":"{read_only:false; response_revision:6317; number_of_response:1; }","duration":"114.715146ms","start":"2026-06-16T01:03:09.452273Z","end":"2026-06-16T01:03:09.566989Z","steps":["trace[1529415416] 'process raft request' (duration: 110.781463ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:03:36.548465Z","caller":"traceutil/trace.go:171","msg":"trace[497396808] transaction","detail":"{read_only:false; response_revision:6608; number_of_response:1; }","duration":"130.520555ms","start":"2026-06-16T01:03:36.417917Z","end":"2026-06-16T01:03:36.548438Z","steps":["trace[497396808] 'process raft request' (duration: 130.343621ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:03:36.548479Z","caller":"traceutil/trace.go:171","msg":"trace[1783374744] linearizableReadLoop","detail":"{readStateIndex:6855; appliedIndex:6855; }","duration":"122.529844ms","start":"2026-06-16T01:03:36.425921Z","end":"2026-06-16T01:03:36.54845Z","steps":["trace[1783374744] 'read index received' (duration: 122.517274ms)","trace[1783374744] 'applied index is now lower than readState.Index' (duration: 10.46µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:03:36.548788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.847272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4784"} {"level":"info","ts":"2026-06-16T01:03:36.548823Z","caller":"traceutil/trace.go:171","msg":"trace[1050131722] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6608; }","duration":"122.928803ms","start":"2026-06-16T01:03:36.425885Z","end":"2026-06-16T01:03:36.548813Z","steps":["trace[1050131722] 'agreement among raft nodes before linearized reading' (duration: 122.707508ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:03:36.731255Z","caller":"traceutil/trace.go:171","msg":"trace[2000053425] transaction","detail":"{read_only:false; response_revision:6611; number_of_response:1; }","duration":"120.563825ms","start":"2026-06-16T01:03:36.610667Z","end":"2026-06-16T01:03:36.731231Z","steps":["trace[2000053425] 'process raft request' (duration: 64.627247ms)","trace[2000053425] 'compare' (duration: 55.61082ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:03:36.962617Z","caller":"traceutil/trace.go:171","msg":"trace[222319631] transaction","detail":"{read_only:false; response_revision:6613; number_of_response:1; }","duration":"140.450505ms","start":"2026-06-16T01:03:36.822147Z","end":"2026-06-16T01:03:36.962598Z","steps":["trace[222319631] 'process raft request' (duration: 139.954503ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:03:46.936807Z","caller":"traceutil/trace.go:171","msg":"trace[1792698958] linearizableReadLoop","detail":"{readStateIndex:6917; appliedIndex:6916; }","duration":"126.497019ms","start":"2026-06-16T01:03:46.810281Z","end":"2026-06-16T01:03:46.936778Z","steps":["trace[1792698958] 'read index received' (duration: 126.230022ms)","trace[1792698958] 'applied index is now lower than readState.Index' (duration: 265.617µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:03:46.937154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.846037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-16T01:03:46.937225Z","caller":"traceutil/trace.go:171","msg":"trace[1364812412] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6667; }","duration":"126.96341ms","start":"2026-06-16T01:03:46.810242Z","end":"2026-06-16T01:03:46.937205Z","steps":["trace[1364812412] 'agreement among raft nodes before linearized reading' (duration: 126.737035ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:03:46.936926Z","caller":"traceutil/trace.go:171","msg":"trace[1547440274] transaction","detail":"{read_only:false; response_revision:6667; number_of_response:1; }","duration":"133.952205ms","start":"2026-06-16T01:03:46.802943Z","end":"2026-06-16T01:03:46.936895Z","steps":["trace[1547440274] 'process raft request' (duration: 133.635427ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:03:47.129969Z","caller":"traceutil/trace.go:171","msg":"trace[1154349721] transaction","detail":"{read_only:false; response_revision:6669; number_of_response:1; }","duration":"122.442304ms","start":"2026-06-16T01:03:47.007509Z","end":"2026-06-16T01:03:47.129951Z","steps":["trace[1154349721] 'process raft request' (duration: 84.194706ms)","trace[1154349721] 'compare' (duration: 38.178066ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:03:48.184266Z","caller":"traceutil/trace.go:171","msg":"trace[1513566566] transaction","detail":"{read_only:false; response_revision:6670; number_of_response:1; }","duration":"261.619957ms","start":"2026-06-16T01:03:47.922625Z","end":"2026-06-16T01:03:48.184245Z","steps":["trace[1513566566] 'process raft request' (duration: 261.500564ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:03:48.504045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.982694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T01:03:48.504224Z","caller":"traceutil/trace.go:171","msg":"trace[70419967] linearizableReadLoop","detail":"{readStateIndex:6922; appliedIndex:6919; }","duration":"321.372983ms","start":"2026-06-16T01:03:48.18279Z","end":"2026-06-16T01:03:48.504163Z","steps":["trace[70419967] 'read index received' (duration: 1.409735ms)","trace[70419967] 'applied index is now lower than readState.Index' (duration: 319.962428ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:03:48.504354Z","caller":"traceutil/trace.go:171","msg":"trace[1011359098] transaction","detail":"{read_only:false; response_revision:6672; number_of_response:1; }","duration":"580.500769ms","start":"2026-06-16T01:03:47.923843Z","end":"2026-06-16T01:03:48.504344Z","steps":["trace[1011359098] 'process raft request' (duration: 580.278393ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:03:48.504447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:03:47.923828Z","time spent":"580.55876ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T01:03:48.504333Z","caller":"traceutil/trace.go:171","msg":"trace[86811326] transaction","detail":"{read_only:false; response_revision:6671; number_of_response:1; }","duration":"581.069053ms","start":"2026-06-16T01:03:47.923238Z","end":"2026-06-16T01:03:48.504307Z","steps":["trace[86811326] 'process raft request' (duration: 463.75388ms)","trace[86811326] 'compare' (duration: 116.878612ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:03:48.504597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:03:47.923219Z","time spent":"581.325599ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:03:48.504579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.724282ms","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-06-16T01:03:48.504732Z","caller":"traceutil/trace.go:171","msg":"trace[801493265] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:6672; }","duration":"321.939677ms","start":"2026-06-16T01:03:48.182751Z","end":"2026-06-16T01:03:48.504691Z","steps":["trace[801493265] 'agreement among raft nodes before linearized reading' (duration: 321.63614ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:03:48.505592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:03:48.182731Z","time spent":"322.778668ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"info","ts":"2026-06-16T01:03:48.687827Z","caller":"traceutil/trace.go:171","msg":"trace[825371354] transaction","detail":"{read_only:false; response_revision:6673; number_of_response:1; }","duration":"256.267052ms","start":"2026-06-16T01:03:48.431496Z","end":"2026-06-16T01:03:48.687763Z","steps":["trace[825371354] 'process raft request' (duration: 249.467584ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:03:48.687952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.640477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T01:03:48.687994Z","caller":"traceutil/trace.go:171","msg":"trace[1389814546] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:6675; }","duration":"451.723858ms","start":"2026-06-16T01:03:48.23626Z","end":"2026-06-16T01:03:48.687984Z","steps":["trace[1389814546] 'agreement among raft nodes before linearized reading' (duration: 451.630656ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:03:48.688037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:03:48.236248Z","time spent":"451.77935ms","remote":"127.0.0.1:57882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-06-16T01:03:48.687842Z","caller":"traceutil/trace.go:171","msg":"trace[1542341557] linearizableReadLoop","detail":"{readStateIndex:6923; appliedIndex:6922; }","duration":"183.563753ms","start":"2026-06-16T01:03:48.504249Z","end":"2026-06-16T01:03:48.687813Z","steps":["trace[1542341557] 'read index received' (duration: 176.744674ms)","trace[1542341557] 'applied index is now lower than readState.Index' (duration: 6.816649ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:03:48.688239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.428881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T01:03:48.688278Z","caller":"traceutil/trace.go:171","msg":"trace[92038866] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:6675; }","duration":"314.508593ms","start":"2026-06-16T01:03:48.373757Z","end":"2026-06-16T01:03:48.688266Z","steps":["trace[92038866] 'agreement among raft nodes before linearized reading' (duration: 314.451422ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:03:48.688273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.835902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4784"} {"level":"warn","ts":"2026-06-16T01:03:48.688306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:03:48.373734Z","time spent":"314.564845ms","remote":"127.0.0.1:40500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true "} {"level":"info","ts":"2026-06-16T01:03:48.688364Z","caller":"traceutil/trace.go:171","msg":"trace[1754563662] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6675; }","duration":"112.943924ms","start":"2026-06-16T01:03:48.575374Z","end":"2026-06-16T01:03:48.688318Z","steps":["trace[1754563662] 'agreement among raft nodes before linearized reading' (duration: 112.78312ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:03:48.688557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.381372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4784"} {"level":"info","ts":"2026-06-16T01:03:48.688593Z","caller":"traceutil/trace.go:171","msg":"trace[1419576285] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6675; }","duration":"344.443904ms","start":"2026-06-16T01:03:48.34414Z","end":"2026-06-16T01:03:48.688584Z","steps":["trace[1419576285] 'agreement among raft nodes before linearized reading' (duration: 344.316651ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:03:48.688654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:03:48.344124Z","time spent":"344.523606ms","remote":"127.0.0.1:57858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4807,"request content":"key:\"/registry/jobs/openstack/horizon-db-sync\" "} {"level":"warn","ts":"2026-06-16T01:03:48.688807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.212541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T01:03:48.688843Z","caller":"traceutil/trace.go:171","msg":"trace[1642897537] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:6675; }","duration":"431.270032ms","start":"2026-06-16T01:03:48.257563Z","end":"2026-06-16T01:03:48.688833Z","steps":["trace[1642897537] 'agreement among raft nodes before linearized reading' (duration: 431.216391ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:03:48.688873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:03:48.257551Z","time spent":"431.314654ms","remote":"127.0.0.1:35982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true "} {"level":"warn","ts":"2026-06-16T01:03:48.689014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.326921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4784"} {"level":"info","ts":"2026-06-16T01:03:48.689055Z","caller":"traceutil/trace.go:171","msg":"trace[474811360] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6675; }","duration":"108.448993ms","start":"2026-06-16T01:03:48.580594Z","end":"2026-06-16T01:03:48.689043Z","steps":["trace[474811360] 'agreement among raft nodes before linearized reading' (duration: 108.276859ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:03:48.689014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.014937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T01:03:48.689371Z","caller":"traceutil/trace.go:171","msg":"trace[1049004175] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:6675; }","duration":"446.404787ms","start":"2026-06-16T01:03:48.242936Z","end":"2026-06-16T01:03:48.689341Z","steps":["trace[1049004175] 'agreement among raft nodes before linearized reading' (duration: 446.033508ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:03:48.68949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:03:48.242893Z","time spent":"446.565551ms","remote":"127.0.0.1:47294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-16T01:04:10.891977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.783095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T01:04:10.892041Z","caller":"traceutil/trace.go:171","msg":"trace[574301825] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6840; }","duration":"183.892228ms","start":"2026-06-16T01:04:10.708135Z","end":"2026-06-16T01:04:10.892027Z","steps":["trace[574301825] 'range keys from in-memory index tree' (duration: 183.697553ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:04:10.955568Z","caller":"traceutil/trace.go:171","msg":"trace[1139799733] transaction","detail":"{read_only:false; response_revision:6841; number_of_response:1; }","duration":"172.789001ms","start":"2026-06-16T01:04:10.782761Z","end":"2026-06-16T01:04:10.95555Z","steps":["trace[1139799733] 'process raft request' (duration: 172.634487ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:04:10.960714Z","caller":"traceutil/trace.go:171","msg":"trace[1867732089] transaction","detail":"{read_only:false; response_revision:6842; number_of_response:1; }","duration":"177.565375ms","start":"2026-06-16T01:04:10.783138Z","end":"2026-06-16T01:04:10.960704Z","steps":["trace[1867732089] 'process raft request' (duration: 177.480463ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:04:10.961201Z","caller":"traceutil/trace.go:171","msg":"trace[278815294] transaction","detail":"{read_only:false; response_revision:6843; number_of_response:1; }","duration":"176.855618ms","start":"2026-06-16T01:04:10.784323Z","end":"2026-06-16T01:04:10.961179Z","steps":["trace[278815294] 'process raft request' (duration: 176.352746ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:04:10.961318Z","caller":"traceutil/trace.go:171","msg":"trace[846803026] linearizableReadLoop","detail":"{readStateIndex:7101; appliedIndex:7099; }","duration":"105.32174ms","start":"2026-06-16T01:04:10.855942Z","end":"2026-06-16T01:04:10.961264Z","steps":["trace[846803026] 'read index received' (duration: 99.680534ms)","trace[846803026] 'applied index is now lower than readState.Index' (duration: 5.545673ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:04:10.964081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.093736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T01:04:10.964166Z","caller":"traceutil/trace.go:171","msg":"trace[2124759549] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:6843; }","duration":"108.24359ms","start":"2026-06-16T01:04:10.855901Z","end":"2026-06-16T01:04:10.964144Z","steps":["trace[2124759549] 'agreement among raft nodes before linearized reading' (duration: 105.74309ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:04:11.271166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.71312ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T01:04:11.271259Z","caller":"traceutil/trace.go:171","msg":"trace[1593785296] linearizableReadLoop","detail":"{readStateIndex:7103; appliedIndex:7102; }","duration":"172.771575ms","start":"2026-06-16T01:04:11.098473Z","end":"2026-06-16T01:04:11.271245Z","steps":["trace[1593785296] 'read index received' (duration: 55.815629ms)","trace[1593785296] 'applied index is now lower than readState.Index' (duration: 116.955085ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:04:11.271378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.907338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/operatorpolicies.rabbitmq.com\" ","response":"range_response_count:1 size:6371"} {"level":"info","ts":"2026-06-16T01:04:11.271456Z","caller":"traceutil/trace.go:171","msg":"trace[1721334869] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/operatorpolicies.rabbitmq.com; range_end:; response_count:1; response_revision:6845; }","duration":"172.960529ms","start":"2026-06-16T01:04:11.098443Z","end":"2026-06-16T01:04:11.271404Z","steps":["trace[1721334869] 'agreement among raft nodes before linearized reading' (duration: 172.836566ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:04:11.271761Z","caller":"traceutil/trace.go:171","msg":"trace[1462851204] transaction","detail":"{read_only:false; response_revision:6845; number_of_response:1; }","duration":"176.525285ms","start":"2026-06-16T01:04:11.095188Z","end":"2026-06-16T01:04:11.271714Z","steps":["trace[1462851204] 'process raft request' (duration: 59.19595ms)","trace[1462851204] 'compare' (duration: 116.573707ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:06:02.059408Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5554} {"level":"info","ts":"2026-06-16T01:06:02.186875Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5554,"took":"125.861053ms","hash":3568812668,"current-db-size-bytes":53751808,"current-db-size":"54 MB","current-db-size-in-use-bytes":18452480,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-06-16T01:06:02.186937Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3568812668,"revision":5554,"compact-revision":2853}