{"level":"warn","ts":"2026-06-08T22:15:00.735919Z","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-08T22:15:00.736019Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-08T22:15:00.736081Z","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-08T22:15:00.736093Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-06-08T22:15:00.736112Z","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-08T22:15:00.736648Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-06-08T22:15:00.736737Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-08T22:15:00.741836Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.749746ms"} {"level":"info","ts":"2026-06-08T22:15:00.748414Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-06-08T22:15:00.748538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-06-08T22:15:00.748574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-06-08T22:15:00.748596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-08T22:15:00.748614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-06-08T22:15:00.748692Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-06-08T22:15:00.754759Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-08T22:15:00.756243Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-08T22:15:00.75725Z","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-08T22:15:00.759499Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-08T22:15:00.759657Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-08T22:15:00.759725Z","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-08T22:15:00.759783Z","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-08T22:15:00.759793Z","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-08T22:15:00.761823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-06-08T22:15:00.762193Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-06-08T22:15:00.764897Z","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-08T22:15:00.764994Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-06-08T22:15:00.765042Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-06-08T22:15:00.765238Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-08T22:15:00.765325Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-08T22:15:00.950983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-06-08T22:15:00.951029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-06-08T22:15:00.951055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-06-08T22:15:00.95107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-06-08T22:15:00.951075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-06-08T22:15:00.951083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-06-08T22:15:00.95109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-06-08T22:15:00.956278Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-08T22:15:00.95722Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-08T22:15:00.957217Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-06-08T22:15:00.957263Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-08T22:15:00.95747Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-08T22:15:00.957521Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-08T22:15:00.960478Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"info","ts":"2026-06-08T22:15:00.961002Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-06-08T22:15:00.961104Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-08T22:15:00.961136Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-08T22:15:00.961154Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-06-08T22:15:19.569346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.249451ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-08T22:15:19.569566Z","caller":"traceutil/trace.go:171","msg":"trace[1236715757] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:429; }","duration":"236.332768ms","start":"2026-06-08T22:15:19.333163Z","end":"2026-06-08T22:15:19.569495Z","steps":["trace[1236715757] 'read index received' (duration: 67.672887ms)","trace[1236715757] 'applied index is now lower than readState.Index' (duration: 168.657861ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:15:19.569778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.582943ms","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-08T22:15:19.569921Z","caller":"traceutil/trace.go:171","msg":"trace[1787240243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:418; }","duration":"236.796658ms","start":"2026-06-08T22:15:19.333088Z","end":"2026-06-08T22:15:19.569884Z","steps":["trace[1787240243] 'agreement among raft nodes before linearized reading' (duration: 236.546612ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:15:45.078682Z","caller":"traceutil/trace.go:171","msg":"trace[114646353] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"331.663836ms","start":"2026-06-08T22:15:44.746993Z","end":"2026-06-08T22:15:45.078656Z","steps":["trace[114646353] 'process raft request' (duration: 329.968437ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:15:45.07928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:15:44.746926Z","time spent":"331.844112ms","remote":"127.0.0.1:36070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:15:45.678445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.477062ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-08T22:15:45.678589Z","caller":"traceutil/trace.go:171","msg":"trace[1902878991] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"538.839197ms","start":"2026-06-08T22:15:45.139729Z","end":"2026-06-08T22:15:45.678569Z","steps":["trace[1902878991] 'process raft request' (duration: 93.17159ms)","trace[1902878991] 'compare' (duration: 445.340129ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:15:45.678643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:15:45.139709Z","time spent":"538.912258ms","remote":"127.0.0.1:36156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-08T22:17:01.872002Z","caller":"traceutil/trace.go:171","msg":"trace[1434575204] transaction","detail":"{read_only:false; response_revision:1570; number_of_response:1; }","duration":"122.246106ms","start":"2026-06-08T22:17:01.749726Z","end":"2026-06-08T22:17:01.871972Z","steps":["trace[1434575204] 'process raft request' (duration: 122.029701ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:02.078249Z","caller":"traceutil/trace.go:171","msg":"trace[1253735215] transaction","detail":"{read_only:false; response_revision:1574; number_of_response:1; }","duration":"115.51451ms","start":"2026-06-08T22:17:01.962704Z","end":"2026-06-08T22:17:02.078219Z","steps":["trace[1253735215] 'process raft request' (duration: 115.329085ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:02.222847Z","caller":"traceutil/trace.go:171","msg":"trace[1147594236] transaction","detail":"{read_only:false; response_revision:1578; number_of_response:1; }","duration":"131.512586ms","start":"2026-06-08T22:17:02.091305Z","end":"2026-06-08T22:17:02.222817Z","steps":["trace[1147594236] 'process raft request' (duration: 131.380932ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:02.222902Z","caller":"traceutil/trace.go:171","msg":"trace[1351441014] transaction","detail":"{read_only:false; response_revision:1577; number_of_response:1; }","duration":"132.258784ms","start":"2026-06-08T22:17:02.090616Z","end":"2026-06-08T22:17:02.222875Z","steps":["trace[1351441014] 'process raft request' (duration: 75.142995ms)","trace[1351441014] 'compare' (duration: 55.52413ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:17:02.233198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.906364ms","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-08T22:17:02.233306Z","caller":"traceutil/trace.go:171","msg":"trace[182287924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1586; }","duration":"104.088559ms","start":"2026-06-08T22:17:02.129196Z","end":"2026-06-08T22:17:02.233285Z","steps":["trace[182287924] 'agreement among raft nodes before linearized reading' (duration: 103.820293ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:02.390061Z","caller":"traceutil/trace.go:171","msg":"trace[460903519] transaction","detail":"{read_only:false; response_revision:1590; number_of_response:1; }","duration":"151.13461ms","start":"2026-06-08T22:17:02.238883Z","end":"2026-06-08T22:17:02.390017Z","steps":["trace[460903519] 'process raft request' (duration: 136.112859ms)","trace[460903519] 'compare' (duration: 14.770874ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:17:02.39032Z","caller":"traceutil/trace.go:171","msg":"trace[363656977] transaction","detail":"{read_only:false; response_revision:1591; number_of_response:1; }","duration":"149.16566ms","start":"2026-06-08T22:17:02.241128Z","end":"2026-06-08T22:17:02.390293Z","steps":["trace[363656977] 'process raft request' (duration: 148.787191ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:02.390595Z","caller":"traceutil/trace.go:171","msg":"trace[845705321] transaction","detail":"{read_only:false; response_revision:1593; number_of_response:1; }","duration":"146.459524ms","start":"2026-06-08T22:17:02.244091Z","end":"2026-06-08T22:17:02.390551Z","steps":["trace[845705321] 'process raft request' (duration: 146.198107ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:02.39066Z","caller":"traceutil/trace.go:171","msg":"trace[824583073] transaction","detail":"{read_only:false; response_revision:1594; number_of_response:1; }","duration":"146.256278ms","start":"2026-06-08T22:17:02.244292Z","end":"2026-06-08T22:17:02.390549Z","steps":["trace[824583073] 'process raft request' (duration: 146.074394ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:02.390762Z","caller":"traceutil/trace.go:171","msg":"trace[1167149539] transaction","detail":"{read_only:false; response_revision:1595; number_of_response:1; }","duration":"146.263109ms","start":"2026-06-08T22:17:02.244463Z","end":"2026-06-08T22:17:02.390726Z","steps":["trace[1167149539] 'process raft request' (duration: 145.942091ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:02.390915Z","caller":"traceutil/trace.go:171","msg":"trace[155360485] transaction","detail":"{read_only:false; response_revision:1592; number_of_response:1; }","duration":"149.678733ms","start":"2026-06-08T22:17:02.241192Z","end":"2026-06-08T22:17:02.390871Z","steps":["trace[155360485] 'process raft request' (duration: 148.987106ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:02.627145Z","caller":"traceutil/trace.go:171","msg":"trace[1118725531] transaction","detail":"{read_only:false; response_revision:1603; number_of_response:1; }","duration":"136.54985ms","start":"2026-06-08T22:17:02.490563Z","end":"2026-06-08T22:17:02.627113Z","steps":["trace[1118725531] 'process raft request' (duration: 136.210341ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:02.627305Z","caller":"traceutil/trace.go:171","msg":"trace[981452763] transaction","detail":"{read_only:false; response_revision:1605; number_of_response:1; }","duration":"130.305386ms","start":"2026-06-08T22:17:02.496979Z","end":"2026-06-08T22:17:02.627285Z","steps":["trace[981452763] 'process raft request' (duration: 129.906006ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:02.627312Z","caller":"traceutil/trace.go:171","msg":"trace[1802286590] transaction","detail":"{read_only:false; response_revision:1602; number_of_response:1; }","duration":"137.246418ms","start":"2026-06-08T22:17:02.490023Z","end":"2026-06-08T22:17:02.627269Z","steps":["trace[1802286590] 'process raft request' (duration: 136.614502ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:02.627377Z","caller":"traceutil/trace.go:171","msg":"trace[1094811540] transaction","detail":"{read_only:false; response_revision:1604; number_of_response:1; }","duration":"136.703714ms","start":"2026-06-08T22:17:02.490648Z","end":"2026-06-08T22:17:02.627352Z","steps":["trace[1094811540] 'process raft request' (duration: 136.201181ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:03.906913Z","caller":"traceutil/trace.go:171","msg":"trace[1350010548] transaction","detail":"{read_only:false; response_revision:1677; number_of_response:1; }","duration":"144.146137ms","start":"2026-06-08T22:17:03.76274Z","end":"2026-06-08T22:17:03.906886Z","steps":["trace[1350010548] 'process raft request' (duration: 143.991243ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:17.965084Z","caller":"traceutil/trace.go:171","msg":"trace[1455945599] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"352.559779ms","start":"2026-06-08T22:17:17.612499Z","end":"2026-06-08T22:17:17.965059Z","steps":["trace[1455945599] 'process raft request' (duration: 273.5438ms)","trace[1455945599] 'compare' (duration: 78.194439ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:17:17.965243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:17.612462Z","time spent":"352.703843ms","remote":"127.0.0.1:36142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10966,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:17:18.151634Z","caller":"traceutil/trace.go:171","msg":"trace[331317233] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"180.544545ms","start":"2026-06-08T22:17:17.971058Z","end":"2026-06-08T22:17:18.151602Z","steps":["trace[331317233] 'process raft request' (duration: 174.709681ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:18.173319Z","caller":"traceutil/trace.go:171","msg":"trace[405336356] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"201.758709ms","start":"2026-06-08T22:17:17.971526Z","end":"2026-06-08T22:17:18.173285Z","steps":["trace[405336356] 'process raft request' (duration: 201.469292ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:18.173695Z","caller":"traceutil/trace.go:171","msg":"trace[857127492] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"201.412471ms","start":"2026-06-08T22:17:17.972265Z","end":"2026-06-08T22:17:18.173678Z","steps":["trace[857127492] 'process raft request' (duration: 200.94811ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:18.173828Z","caller":"traceutil/trace.go:171","msg":"trace[1371836525] linearizableReadLoop","detail":"{readStateIndex:1945; appliedIndex:1941; }","duration":"144.321921ms","start":"2026-06-08T22:17:18.029476Z","end":"2026-06-08T22:17:18.173798Z","steps":["trace[1371836525] 'read index received' (duration: 116.31064ms)","trace[1371836525] 'applied index is now lower than readState.Index' (duration: 28.008531ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:17:18.17388Z","caller":"traceutil/trace.go:171","msg":"trace[1311196349] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"119.334394ms","start":"2026-06-08T22:17:18.0545Z","end":"2026-06-08T22:17:18.173834Z","steps":["trace[1311196349] 'process raft request' (duration: 118.984825ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:18.174039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.514605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-08T22:17:18.174103Z","caller":"traceutil/trace.go:171","msg":"trace[5230194] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1877; }","duration":"144.66049ms","start":"2026-06-08T22:17:18.029424Z","end":"2026-06-08T22:17:18.174084Z","steps":["trace[5230194] 'agreement among raft nodes before linearized reading' (duration: 144.455954ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:18.173701Z","caller":"traceutil/trace.go:171","msg":"trace[201411591] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"201.80677ms","start":"2026-06-08T22:17:17.971846Z","end":"2026-06-08T22:17:18.173653Z","steps":["trace[201411591] 'process raft request' (duration: 201.325239ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:18.522844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.651468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-06-08T22:17:18.522958Z","caller":"traceutil/trace.go:171","msg":"trace[2079642198] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1877; }","duration":"103.820192ms","start":"2026-06-08T22:17:18.419098Z","end":"2026-06-08T22:17:18.522918Z","steps":["trace[2079642198] 'range keys from in-memory index tree' (duration: 103.426123ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:18.523091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.602977ms","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-08T22:17:18.523182Z","caller":"traceutil/trace.go:171","msg":"trace[1569288174] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1877; }","duration":"148.767181ms","start":"2026-06-08T22:17:18.374394Z","end":"2026-06-08T22:17:18.523161Z","steps":["trace[1569288174] 'range keys from in-memory index tree' (duration: 148.32508ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:30.726263Z","caller":"traceutil/trace.go:171","msg":"trace[1550277244] transaction","detail":"{read_only:false; response_revision:1982; number_of_response:1; }","duration":"108.354136ms","start":"2026-06-08T22:17:30.617882Z","end":"2026-06-08T22:17:30.726236Z","steps":["trace[1550277244] 'process raft request' (duration: 108.10189ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:33.204495Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680220385357437927,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-08T22:17:33.705402Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680220385357437927,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-08T22:17:34.205817Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680220385357437927,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-08T22:17:34.706529Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680220385357437927,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-08T22:17:35.153244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000018493s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-08T22:17:35.15336Z","caller":"traceutil/trace.go:171","msg":"trace[2076178503] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000182916s","start":"2026-06-08T22:17:33.153156Z","end":"2026-06-08T22:17:35.153339Z","steps":["trace[2076178503] 'agreement among raft nodes before linearized reading' (duration: 2.000039443s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:35.153414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:33.153145Z","time spent":"2.000254339s","remote":"127.0.0.1:35998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/08 22:17:35 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-08T22:17:35.207381Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680220385357437927,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-08T22:17:35.707766Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680220385357437927,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-08T22:17:36.208884Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680220385357437927,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-08T22:17:36.412355Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.798968338s","expected-duration":"1s"} {"level":"info","ts":"2026-06-08T22:17:36.412776Z","caller":"traceutil/trace.go:171","msg":"trace[660828289] transaction","detail":"{read_only:false; response_revision:1990; number_of_response:1; }","duration":"3.799501991s","start":"2026-06-08T22:17:32.613249Z","end":"2026-06-08T22:17:36.412751Z","steps":["trace[660828289] 'process raft request' (duration: 3.799334187s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:36.41295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:32.613222Z","time spent":"3.799643553s","remote":"127.0.0.1:36224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:17:36.44669Z","caller":"traceutil/trace.go:171","msg":"trace[726466387] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"3.714363202s","start":"2026-06-08T22:17:32.732302Z","end":"2026-06-08T22:17:36.446665Z","steps":["trace[726466387] 'process raft request' (duration: 3.714111086s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:36.446854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:32.732284Z","time spent":"3.714486545s","remote":"127.0.0.1:36224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:17:36.447054Z","caller":"traceutil/trace.go:171","msg":"trace[643991526] transaction","detail":"{read_only:false; response_revision:1994; number_of_response:1; }","duration":"2.904587807s","start":"2026-06-08T22:17:33.542444Z","end":"2026-06-08T22:17:36.447031Z","steps":["trace[643991526] 'process raft request' (duration: 2.904535236s)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:36.447086Z","caller":"traceutil/trace.go:171","msg":"trace[2048712939] transaction","detail":"{read_only:false; response_revision:1993; number_of_response:1; }","duration":"3.616649101s","start":"2026-06-08T22:17:32.830419Z","end":"2026-06-08T22:17:36.447068Z","steps":["trace[2048712939] 'process raft request' (duration: 3.616463207s)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:36.447096Z","caller":"traceutil/trace.go:171","msg":"trace[2136935714] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"3.689849532s","start":"2026-06-08T22:17:32.757179Z","end":"2026-06-08T22:17:36.447029Z","steps":["trace[2136935714] 'process raft request' (duration: 3.689418012s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:36.447172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:32.830395Z","time spent":"3.616733753s","remote":"127.0.0.1:36224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:17:36.447255Z","caller":"traceutil/trace.go:171","msg":"trace[544573436] transaction","detail":"{read_only:false; response_revision:1995; number_of_response:1; }","duration":"222.586116ms","start":"2026-06-08T22:17:36.224662Z","end":"2026-06-08T22:17:36.447248Z","steps":["trace[544573436] 'process raft request' (duration: 222.34402ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:36.44717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:33.542422Z","time spent":"2.904687279s","remote":"127.0.0.1:36224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:17:36.447358Z","caller":"traceutil/trace.go:171","msg":"trace[1206567352] linearizableReadLoop","detail":"{readStateIndex:2066; appliedIndex:2061; }","duration":"3.74340542s","start":"2026-06-08T22:17:32.703756Z","end":"2026-06-08T22:17:36.447161Z","steps":["trace[1206567352] 'read index received' (duration: 3.70886289s)","trace[1206567352] 'applied index is now lower than readState.Index' (duration: 34.53958ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:17:36.447595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.743533264s","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-08T22:17:36.44763Z","caller":"traceutil/trace.go:171","msg":"trace[252413429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1995; }","duration":"3.743606306s","start":"2026-06-08T22:17:32.704012Z","end":"2026-06-08T22:17:36.447618Z","steps":["trace[252413429] 'agreement among raft nodes before linearized reading' (duration: 3.743471993s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:36.447609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.743819031s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-08T22:17:36.447662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:32.703991Z","time spent":"3.743663447s","remote":"127.0.0.1:36224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-08T22:17:36.447701Z","caller":"traceutil/trace.go:171","msg":"trace[25741512] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1995; }","duration":"3.743962055s","start":"2026-06-08T22:17:32.703712Z","end":"2026-06-08T22:17:36.447674Z","steps":["trace[25741512] 'agreement among raft nodes before linearized reading' (duration: 3.743664167s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:36.447733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.311477708s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-08T22:17:36.447794Z","caller":"traceutil/trace.go:171","msg":"trace[627362760] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:1995; }","duration":"2.31157823s","start":"2026-06-08T22:17:34.136204Z","end":"2026-06-08T22:17:36.447782Z","steps":["trace[627362760] 'agreement among raft nodes before linearized reading' (duration: 2.311410026s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:36.44779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:32.703691Z","time spent":"3.744083078s","remote":"127.0.0.1:36224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-08T22:17:36.44783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:34.136187Z","time spent":"2.311635131s","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"warn","ts":"2026-06-08T22:17:36.447893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.392852696s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-j6mf8\" ","response":"range_response_count:1 size:5851"} {"level":"warn","ts":"2026-06-08T22:17:36.447912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.966125939s","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-08T22:17:36.447929Z","caller":"traceutil/trace.go:171","msg":"trace[822820934] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-j6mf8; range_end:; response_count:1; response_revision:1995; }","duration":"3.392930417s","start":"2026-06-08T22:17:33.054986Z","end":"2026-06-08T22:17:36.447916Z","steps":["trace[822820934] 'agreement among raft nodes before linearized reading' (duration: 3.392835215s)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:36.44796Z","caller":"traceutil/trace.go:171","msg":"trace[389181650] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1995; }","duration":"2.96621186s","start":"2026-06-08T22:17:33.481736Z","end":"2026-06-08T22:17:36.447948Z","steps":["trace[389181650] 'agreement among raft nodes before linearized reading' (duration: 2.966066147s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:36.447964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:33.054965Z","time spent":"3.392987269s","remote":"127.0.0.1:36142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":5873,"request content":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-j6mf8\" "} {"level":"warn","ts":"2026-06-08T22:17:36.447989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.804689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11985"} {"level":"warn","ts":"2026-06-08T22:17:36.448004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.294365884s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:17:36.448022Z","caller":"traceutil/trace.go:171","msg":"trace[775967146] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1995; }","duration":"677.871571ms","start":"2026-06-08T22:17:35.770141Z","end":"2026-06-08T22:17:36.448012Z","steps":["trace[775967146] 'agreement among raft nodes before linearized reading' (duration: 677.766708ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:36.448038Z","caller":"traceutil/trace.go:171","msg":"trace[2076040995] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1995; }","duration":"1.294434096s","start":"2026-06-08T22:17:35.153595Z","end":"2026-06-08T22:17:36.448029Z","steps":["trace[2076040995] 'agreement among raft nodes before linearized reading' (duration: 1.294383335s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:36.448041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.608843243s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-08T22:17:36.448067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:35.153572Z","time spent":"1.294490267s","remote":"127.0.0.1:35984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-08T22:17:36.448075Z","caller":"traceutil/trace.go:171","msg":"trace[791806561] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1995; }","duration":"2.608909954s","start":"2026-06-08T22:17:33.839156Z","end":"2026-06-08T22:17:36.448066Z","steps":["trace[791806561] 'agreement among raft nodes before linearized reading' (duration: 2.608856713s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:36.448097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.567963ms","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":"warn","ts":"2026-06-08T22:17:36.4481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:33.839137Z","time spent":"2.608956756s","remote":"127.0.0.1:36272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":4,"response size":29,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-06-08T22:17:36.448118Z","caller":"traceutil/trace.go:171","msg":"trace[114894667] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1995; }","duration":"418.626473ms","start":"2026-06-08T22:17:36.029485Z","end":"2026-06-08T22:17:36.448111Z","steps":["trace[114894667] 'agreement among raft nodes before linearized reading' (duration: 418.581062ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:36.448137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:36.029463Z","time spent":"418.668474ms","remote":"127.0.0.1:53386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":17,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-06-08T22:17:36.448195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.648488488s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-08T22:17:36.448204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.759068365s","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-08T22:17:36.448228Z","caller":"traceutil/trace.go:171","msg":"trace[2050422714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1995; }","duration":"3.64857618s","start":"2026-06-08T22:17:32.799643Z","end":"2026-06-08T22:17:36.448219Z","steps":["trace[2050422714] 'agreement among raft nodes before linearized reading' (duration: 3.648464577s)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:36.448235Z","caller":"traceutil/trace.go:171","msg":"trace[341011856] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:1995; }","duration":"1.759151628s","start":"2026-06-08T22:17:34.689074Z","end":"2026-06-08T22:17:36.448226Z","steps":["trace[341011856] 'agreement among raft nodes before linearized reading' (duration: 1.759099446s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:36.448256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:32.799612Z","time spent":"3.648638651s","remote":"127.0.0.1:36224","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-08T22:17:36.448265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:34.689053Z","time spent":"1.759204228s","remote":"127.0.0.1:58136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":2,"response size":29,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"warn","ts":"2026-06-08T22:17:36.448321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.740285255s","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-08T22:17:36.448362Z","caller":"traceutil/trace.go:171","msg":"trace[521401376] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1995; }","duration":"2.740359536s","start":"2026-06-08T22:17:33.707993Z","end":"2026-06-08T22:17:36.448352Z","steps":["trace[521401376] 'agreement among raft nodes before linearized reading' (duration: 2.740253583s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:36.448375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.706609465s","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-08T22:17:36.448398Z","caller":"traceutil/trace.go:171","msg":"trace[1893670380] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1995; }","duration":"3.706674077s","start":"2026-06-08T22:17:32.741717Z","end":"2026-06-08T22:17:36.448391Z","steps":["trace[1893670380] 'agreement among raft nodes before linearized reading' (duration: 3.706584745s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:36.448425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:32.741696Z","time spent":"3.706724298s","remote":"127.0.0.1:35454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-08T22:17:36.448438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.911992197s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-06-08T22:17:36.448469Z","caller":"traceutil/trace.go:171","msg":"trace[68541212] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1995; }","duration":"2.912060048s","start":"2026-06-08T22:17:33.5364Z","end":"2026-06-08T22:17:36.44846Z","steps":["trace[68541212] 'agreement among raft nodes before linearized reading' (duration: 2.911971426s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:36.448488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.854206495s","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-08T22:17:36.448395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:33.707975Z","time spent":"2.740413208s","remote":"127.0.0.1:36224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-08T22:17:36.448505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:33.536377Z","time spent":"2.912121959s","remote":"127.0.0.1:36224","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":"info","ts":"2026-06-08T22:17:36.448521Z","caller":"traceutil/trace.go:171","msg":"trace[622472844] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1995; }","duration":"1.854275476s","start":"2026-06-08T22:17:34.594234Z","end":"2026-06-08T22:17:36.448509Z","steps":["trace[622472844] 'agreement among raft nodes before linearized reading' (duration: 1.854191044s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:36.448549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:34.594217Z","time spent":"1.854326627s","remote":"127.0.0.1:36224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-06-08T22:17:36.447991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:33.481717Z","time spent":"2.966267232s","remote":"127.0.0.1:36224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-08T22:17:36.448558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:32.757163Z","time spent":"3.690076957s","remote":"127.0.0.1:36224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:17:36.448044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:35.770125Z","time spent":"677.915111ms","remote":"127.0.0.1:36142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12007,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-06-08T22:17:36.55388Z","caller":"traceutil/trace.go:171","msg":"trace[409184015] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"100.901498ms","start":"2026-06-08T22:17:36.452956Z","end":"2026-06-08T22:17:36.553857Z","steps":["trace[409184015] 'process raft request' (duration: 100.833376ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:36.553892Z","caller":"traceutil/trace.go:171","msg":"trace[1181785455] transaction","detail":"{read_only:false; response_revision:1997; number_of_response:1; }","duration":"101.233255ms","start":"2026-06-08T22:17:36.452611Z","end":"2026-06-08T22:17:36.553844Z","steps":["trace[1181785455] 'process raft request' (duration: 101.116743ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:36.554204Z","caller":"traceutil/trace.go:171","msg":"trace[1861508196] transaction","detail":"{read_only:false; response_revision:1999; number_of_response:1; }","duration":"101.200316ms","start":"2026-06-08T22:17:36.452973Z","end":"2026-06-08T22:17:36.554173Z","steps":["trace[1861508196] 'process raft request' (duration: 100.849777ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:36.554232Z","caller":"traceutil/trace.go:171","msg":"trace[1457346964] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"101.213816ms","start":"2026-06-08T22:17:36.453006Z","end":"2026-06-08T22:17:36.55422Z","steps":["trace[1457346964] 'process raft request' (duration: 101.084873ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:36.554393Z","caller":"traceutil/trace.go:171","msg":"trace[1515823265] transaction","detail":"{read_only:false; response_revision:2001; number_of_response:1; }","duration":"100.745664ms","start":"2026-06-08T22:17:36.453636Z","end":"2026-06-08T22:17:36.554382Z","steps":["trace[1515823265] 'process raft request' (duration: 100.543179ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:36.554433Z","caller":"traceutil/trace.go:171","msg":"trace[1288400967] transaction","detail":"{read_only:false; response_revision:2002; number_of_response:1; }","duration":"100.763275ms","start":"2026-06-08T22:17:36.453654Z","end":"2026-06-08T22:17:36.554417Z","steps":["trace[1288400967] 'process raft request' (duration: 100.636662ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:36.554556Z","caller":"traceutil/trace.go:171","msg":"trace[670918303] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"100.1833ms","start":"2026-06-08T22:17:36.454338Z","end":"2026-06-08T22:17:36.554521Z","steps":["trace[670918303] 'process raft request' (duration: 100.039317ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:36.554616Z","caller":"traceutil/trace.go:171","msg":"trace[373550011] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"102.025386ms","start":"2026-06-08T22:17:36.452338Z","end":"2026-06-08T22:17:36.554364Z","steps":["trace[373550011] 'process raft request' (duration: 76.794119ms)","trace[373550011] 'compare' (duration: 24.422016ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:17:36.945731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.277507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-08T22:17:36.945813Z","caller":"traceutil/trace.go:171","msg":"trace[273661749] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:2014; }","duration":"111.41616ms","start":"2026-06-08T22:17:36.834379Z","end":"2026-06-08T22:17:36.945795Z","steps":["trace[273661749] 'count revisions from in-memory index tree' (duration: 111.160124ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:38.638581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.416067ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-08T22:17:38.638796Z","caller":"traceutil/trace.go:171","msg":"trace[1409113445] linearizableReadLoop","detail":"{readStateIndex:2091; appliedIndex:2088; }","duration":"174.93947ms","start":"2026-06-08T22:17:38.463841Z","end":"2026-06-08T22:17:38.63878Z","steps":["trace[1409113445] 'read index received' (duration: 5.259107ms)","trace[1409113445] 'applied index is now lower than readState.Index' (duration: 169.679363ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:17:38.638872Z","caller":"traceutil/trace.go:171","msg":"trace[46733310] transaction","detail":"{read_only:false; response_revision:2018; number_of_response:1; }","duration":"182.45702ms","start":"2026-06-08T22:17:38.456405Z","end":"2026-06-08T22:17:38.638862Z","steps":["trace[46733310] 'process raft request' (duration: 182.337748ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:38.63899Z","caller":"traceutil/trace.go:171","msg":"trace[1653591772] transaction","detail":"{read_only:false; response_revision:2016; number_of_response:1; }","duration":"185.561394ms","start":"2026-06-08T22:17:38.453375Z","end":"2026-06-08T22:17:38.638936Z","steps":["trace[1653591772] 'process raft request' (duration: 15.712227ms)","trace[1653591772] 'compare' (duration: 169.319215ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:17:38.63905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.209096ms","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-08T22:17:38.639117Z","caller":"traceutil/trace.go:171","msg":"trace[2005506192] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2018; }","duration":"175.313379ms","start":"2026-06-08T22:17:38.463785Z","end":"2026-06-08T22:17:38.639098Z","steps":["trace[2005506192] 'agreement among raft nodes before linearized reading' (duration: 175.125694ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:38.639409Z","caller":"traceutil/trace.go:171","msg":"trace[1679417165] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"183.31973ms","start":"2026-06-08T22:17:38.456053Z","end":"2026-06-08T22:17:38.639373Z","steps":["trace[1679417165] 'process raft request' (duration: 182.627194ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:38.803642Z","caller":"traceutil/trace.go:171","msg":"trace[802163379] linearizableReadLoop","detail":"{readStateIndex:2092; appliedIndex:2091; }","duration":"164.781565ms","start":"2026-06-08T22:17:38.638834Z","end":"2026-06-08T22:17:38.803615Z","steps":["trace[802163379] 'read index received' (duration: 104.524864ms)","trace[802163379] 'applied index is now lower than readState.Index' (duration: 60.255751ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:17:38.803807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.144818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:17:38.803851Z","caller":"traceutil/trace.go:171","msg":"trace[1771888366] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:2019; }","duration":"326.265171ms","start":"2026-06-08T22:17:38.477574Z","end":"2026-06-08T22:17:38.803839Z","steps":["trace[1771888366] 'agreement among raft nodes before linearized reading' (duration: 326.141578ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:38.803895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:38.477553Z","time spent":"326.332812ms","remote":"127.0.0.1:33966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-06-08T22:17:38.803901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.219351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-08T22:17:38.803911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.765594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-08T22:17:38.803925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.040156ms","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-08T22:17:38.803967Z","caller":"traceutil/trace.go:171","msg":"trace[873927746] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2019; }","duration":"245.860376ms","start":"2026-06-08T22:17:38.558091Z","end":"2026-06-08T22:17:38.803951Z","steps":["trace[873927746] 'agreement among raft nodes before linearized reading' (duration: 245.694892ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:38.803964Z","caller":"traceutil/trace.go:171","msg":"trace[1296248673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2019; }","duration":"245.307913ms","start":"2026-06-08T22:17:38.558639Z","end":"2026-06-08T22:17:38.803947Z","steps":["trace[1296248673] 'agreement among raft nodes before linearized reading' (duration: 245.086357ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:38.803942Z","caller":"traceutil/trace.go:171","msg":"trace[1132826368] transaction","detail":"{read_only:false; response_revision:2019; number_of_response:1; }","duration":"232.108756ms","start":"2026-06-08T22:17:38.571798Z","end":"2026-06-08T22:17:38.803906Z","steps":["trace[1132826368] 'process raft request' (duration: 171.62799ms)","trace[1132826368] 'compare' (duration: 60.042945ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:17:38.804008Z","caller":"traceutil/trace.go:171","msg":"trace[138768407] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2019; }","duration":"245.18812ms","start":"2026-06-08T22:17:38.558801Z","end":"2026-06-08T22:17:38.803989Z","steps":["trace[138768407] 'agreement among raft nodes before linearized reading' (duration: 244.879092ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:38.804248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.967675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-08T22:17:38.804416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.719653ms","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-08T22:17:38.804471Z","caller":"traceutil/trace.go:171","msg":"trace[553094168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2019; }","duration":"245.804565ms","start":"2026-06-08T22:17:38.558653Z","end":"2026-06-08T22:17:38.804457Z","steps":["trace[553094168] 'agreement among raft nodes before linearized reading' (duration: 245.106618ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:38.804561Z","caller":"traceutil/trace.go:171","msg":"trace[345917845] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2019; }","duration":"245.086097ms","start":"2026-06-08T22:17:38.559228Z","end":"2026-06-08T22:17:38.804314Z","steps":["trace[345917845] 'agreement among raft nodes before linearized reading' (duration: 244.829241ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:40.698908Z","caller":"traceutil/trace.go:171","msg":"trace[812363224] transaction","detail":"{read_only:false; response_revision:2026; number_of_response:1; }","duration":"265.069458ms","start":"2026-06-08T22:17:40.433813Z","end":"2026-06-08T22:17:40.698882Z","steps":["trace[812363224] 'process raft request' (duration: 264.904104ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:40.83278Z","caller":"traceutil/trace.go:171","msg":"trace[1901067824] linearizableReadLoop","detail":"{readStateIndex:2103; appliedIndex:2099; }","duration":"145.519552ms","start":"2026-06-08T22:17:40.687226Z","end":"2026-06-08T22:17:40.832745Z","steps":["trace[1901067824] 'read index received' (duration: 11.505237ms)","trace[1901067824] 'applied index is now lower than readState.Index' (duration: 134.013045ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:17:40.832797Z","caller":"traceutil/trace.go:171","msg":"trace[2002410695] transaction","detail":"{read_only:false; response_revision:2028; number_of_response:1; }","duration":"188.483125ms","start":"2026-06-08T22:17:40.644285Z","end":"2026-06-08T22:17:40.832768Z","steps":["trace[2002410695] 'process raft request' (duration: 188.373763ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:40.832834Z","caller":"traceutil/trace.go:171","msg":"trace[1903277490] transaction","detail":"{read_only:false; response_revision:2029; number_of_response:1; }","duration":"187.937222ms","start":"2026-06-08T22:17:40.644856Z","end":"2026-06-08T22:17:40.832794Z","steps":["trace[1903277490] 'process raft request' (duration: 187.84398ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:40.832874Z","caller":"traceutil/trace.go:171","msg":"trace[1713730775] transaction","detail":"{read_only:false; response_revision:2027; number_of_response:1; }","duration":"188.569147ms","start":"2026-06-08T22:17:40.644286Z","end":"2026-06-08T22:17:40.832855Z","steps":["trace[1713730775] 'process raft request' (duration: 152.796296ms)","trace[1713730775] 'compare' (duration: 35.411513ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:17:40.833305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.055684ms","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-08T22:17:40.833403Z","caller":"traceutil/trace.go:171","msg":"trace[2030770116] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2029; }","duration":"146.202068ms","start":"2026-06-08T22:17:40.68717Z","end":"2026-06-08T22:17:40.833372Z","steps":["trace[2030770116] 'agreement among raft nodes before linearized reading' (duration: 145.704826ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:47.04117Z","caller":"traceutil/trace.go:171","msg":"trace[1289713261] transaction","detail":"{read_only:false; response_revision:2057; number_of_response:1; }","duration":"247.382882ms","start":"2026-06-08T22:17:46.793765Z","end":"2026-06-08T22:17:47.041148Z","steps":["trace[1289713261] 'process raft request' (duration: 247.233779ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:47.063534Z","caller":"traceutil/trace.go:171","msg":"trace[122019748] transaction","detail":"{read_only:false; response_revision:2058; number_of_response:1; }","duration":"198.015294ms","start":"2026-06-08T22:17:46.865493Z","end":"2026-06-08T22:17:47.063508Z","steps":["trace[122019748] 'process raft request' (duration: 197.83534ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:47.063744Z","caller":"traceutil/trace.go:171","msg":"trace[1767245087] transaction","detail":"{read_only:false; response_revision:2059; number_of_response:1; }","duration":"189.221303ms","start":"2026-06-08T22:17:46.874513Z","end":"2026-06-08T22:17:47.063735Z","steps":["trace[1767245087] 'process raft request' (duration: 188.954556ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:47.063756Z","caller":"traceutil/trace.go:171","msg":"trace[1041109363] linearizableReadLoop","detail":"{readStateIndex:2135; appliedIndex:2131; }","duration":"177.281315ms","start":"2026-06-08T22:17:46.886455Z","end":"2026-06-08T22:17:47.063737Z","steps":["trace[1041109363] 'read index received' (duration: 154.547758ms)","trace[1041109363] 'applied index is now lower than readState.Index' (duration: 22.732537ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:17:47.063825Z","caller":"traceutil/trace.go:171","msg":"trace[1763553344] transaction","detail":"{read_only:false; response_revision:2060; number_of_response:1; }","duration":"188.635869ms","start":"2026-06-08T22:17:46.875177Z","end":"2026-06-08T22:17:47.063813Z","steps":["trace[1763553344] 'process raft request' (duration: 188.492035ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:47.063993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.349143ms","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-08T22:17:47.064047Z","caller":"traceutil/trace.go:171","msg":"trace[293480442] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2060; }","duration":"176.455886ms","start":"2026-06-08T22:17:46.887579Z","end":"2026-06-08T22:17:47.064035Z","steps":["trace[293480442] 'agreement among raft nodes before linearized reading' (duration: 176.294192ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:47.064099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.644444ms","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-08T22:17:47.064139Z","caller":"traceutil/trace.go:171","msg":"trace[1754784198] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2060; }","duration":"177.722386ms","start":"2026-06-08T22:17:46.886401Z","end":"2026-06-08T22:17:47.064124Z","steps":["trace[1754784198] 'agreement among raft nodes before linearized reading' (duration: 177.426329ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:47.210888Z","caller":"traceutil/trace.go:171","msg":"trace[1886414745] transaction","detail":"{read_only:false; response_revision:2062; number_of_response:1; }","duration":"142.463988ms","start":"2026-06-08T22:17:47.068397Z","end":"2026-06-08T22:17:47.210861Z","steps":["trace[1886414745] 'process raft request' (duration: 88.10788ms)","trace[1886414745] 'compare' (duration: 54.175064ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:17:47.210891Z","caller":"traceutil/trace.go:171","msg":"trace[1906211883] transaction","detail":"{read_only:false; response_revision:2063; number_of_response:1; }","duration":"142.10118ms","start":"2026-06-08T22:17:47.068767Z","end":"2026-06-08T22:17:47.210868Z","steps":["trace[1906211883] 'process raft request' (duration: 142.025618ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:47.211256Z","caller":"traceutil/trace.go:171","msg":"trace[1934784887] transaction","detail":"{read_only:false; response_revision:2064; number_of_response:1; }","duration":"142.406027ms","start":"2026-06-08T22:17:47.068838Z","end":"2026-06-08T22:17:47.211244Z","steps":["trace[1934784887] 'process raft request' (duration: 141.994487ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:47.211611Z","caller":"traceutil/trace.go:171","msg":"trace[688834970] transaction","detail":"{read_only:false; response_revision:2065; number_of_response:1; }","duration":"142.694924ms","start":"2026-06-08T22:17:47.068889Z","end":"2026-06-08T22:17:47.211584Z","steps":["trace[688834970] 'process raft request' (duration: 142.157471ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:49.231321Z","caller":"traceutil/trace.go:171","msg":"trace[1398090575] linearizableReadLoop","detail":"{readStateIndex:2144; appliedIndex:2141; }","duration":"153.204196ms","start":"2026-06-08T22:17:49.078096Z","end":"2026-06-08T22:17:49.2313Z","steps":["trace[1398090575] 'read index received' (duration: 4.516319ms)","trace[1398090575] 'applied index is now lower than readState.Index' (duration: 148.686817ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:17:49.231379Z","caller":"traceutil/trace.go:171","msg":"trace[771391725] transaction","detail":"{read_only:false; response_revision:2068; number_of_response:1; }","duration":"162.301346ms","start":"2026-06-08T22:17:49.069058Z","end":"2026-06-08T22:17:49.231359Z","steps":["trace[771391725] 'process raft request' (duration: 162.008779ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:49.231435Z","caller":"traceutil/trace.go:171","msg":"trace[1297744656] transaction","detail":"{read_only:false; response_revision:2069; number_of_response:1; }","duration":"161.822234ms","start":"2026-06-08T22:17:49.069602Z","end":"2026-06-08T22:17:49.231424Z","steps":["trace[1297744656] 'process raft request' (duration: 161.6418ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:49.231621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.504403ms","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-08T22:17:49.231689Z","caller":"traceutil/trace.go:171","msg":"trace[1290760010] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2069; }","duration":"153.605046ms","start":"2026-06-08T22:17:49.078067Z","end":"2026-06-08T22:17:49.231672Z","steps":["trace[1290760010] 'agreement among raft nodes before linearized reading' (duration: 153.431102ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:49.25711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.090548ms","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-08T22:17:49.257154Z","caller":"traceutil/trace.go:171","msg":"trace[1638265027] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2070; }","duration":"164.17962ms","start":"2026-06-08T22:17:49.092964Z","end":"2026-06-08T22:17:49.257144Z","steps":["trace[1638265027] 'agreement among raft nodes before linearized reading' (duration: 164.042497ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:49.257457Z","caller":"traceutil/trace.go:171","msg":"trace[1195102584] transaction","detail":"{read_only:false; response_revision:2070; number_of_response:1; }","duration":"154.094078ms","start":"2026-06-08T22:17:49.103337Z","end":"2026-06-08T22:17:49.257431Z","steps":["trace[1195102584] 'process raft request' (duration: 153.585796ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:49.431873Z","caller":"traceutil/trace.go:171","msg":"trace[1599608302] transaction","detail":"{read_only:false; response_revision:2073; number_of_response:1; }","duration":"169.094439ms","start":"2026-06-08T22:17:49.262758Z","end":"2026-06-08T22:17:49.431852Z","steps":["trace[1599608302] 'process raft request' (duration: 168.965135ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:49.431877Z","caller":"traceutil/trace.go:171","msg":"trace[1360875432] transaction","detail":"{read_only:false; response_revision:2072; number_of_response:1; }","duration":"170.604375ms","start":"2026-06-08T22:17:49.261241Z","end":"2026-06-08T22:17:49.431845Z","steps":["trace[1360875432] 'process raft request' (duration: 153.512563ms)","trace[1360875432] 'compare' (duration: 16.668961ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:17:49.432028Z","caller":"traceutil/trace.go:171","msg":"trace[1151858157] transaction","detail":"{read_only:false; response_revision:2074; number_of_response:1; }","duration":"167.991102ms","start":"2026-06-08T22:17:49.264013Z","end":"2026-06-08T22:17:49.432004Z","steps":["trace[1151858157] 'process raft request' (duration: 167.809318ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:49.432101Z","caller":"traceutil/trace.go:171","msg":"trace[1944505374] transaction","detail":"{read_only:false; response_revision:2075; number_of_response:1; }","duration":"167.90505ms","start":"2026-06-08T22:17:49.264174Z","end":"2026-06-08T22:17:49.432079Z","steps":["trace[1944505374] 'process raft request' (duration: 167.733946ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:50.768759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.816174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:17:50.768868Z","caller":"traceutil/trace.go:171","msg":"trace[681898608] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2076; }","duration":"161.993198ms","start":"2026-06-08T22:17:50.606856Z","end":"2026-06-08T22:17:50.76885Z","steps":["trace[681898608] 'count revisions from in-memory index tree' (duration: 161.703111ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:18:17.834484Z","caller":"traceutil/trace.go:171","msg":"trace[523863144] transaction","detail":"{read_only:false; response_revision:2231; number_of_response:1; }","duration":"181.463356ms","start":"2026-06-08T22:18:17.652973Z","end":"2026-06-08T22:18:17.834436Z","steps":["trace[523863144] 'process raft request' (duration: 149.274642ms)","trace[523863144] 'compare' (duration: 31.882427ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:18:17.843027Z","caller":"traceutil/trace.go:171","msg":"trace[779124652] transaction","detail":"{read_only:false; response_revision:2233; number_of_response:1; }","duration":"189.12968ms","start":"2026-06-08T22:18:17.653859Z","end":"2026-06-08T22:18:17.842989Z","steps":["trace[779124652] 'process raft request' (duration: 189.040478ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:18:17.843199Z","caller":"traceutil/trace.go:171","msg":"trace[633401722] transaction","detail":"{read_only:false; response_revision:2232; number_of_response:1; }","duration":"189.688013ms","start":"2026-06-08T22:18:17.653387Z","end":"2026-06-08T22:18:17.843075Z","steps":["trace[633401722] 'process raft request' (duration: 189.339185ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:18:23.605533Z","caller":"traceutil/trace.go:171","msg":"trace[1823015099] transaction","detail":"{read_only:false; response_revision:2259; number_of_response:1; }","duration":"196.887367ms","start":"2026-06-08T22:18:23.408608Z","end":"2026-06-08T22:18:23.605495Z","steps":["trace[1823015099] 'process raft request' (duration: 196.716333ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:18:23.642131Z","caller":"traceutil/trace.go:171","msg":"trace[1440492966] linearizableReadLoop","detail":"{readStateIndex:2343; appliedIndex:2341; }","duration":"187.309006ms","start":"2026-06-08T22:18:23.454797Z","end":"2026-06-08T22:18:23.642106Z","steps":["trace[1440492966] 'read index received' (duration: 150.647864ms)","trace[1440492966] 'applied index is now lower than readState.Index' (duration: 36.660192ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:18:23.64223Z","caller":"traceutil/trace.go:171","msg":"trace[136694479] transaction","detail":"{read_only:false; response_revision:2260; number_of_response:1; }","duration":"212.41481ms","start":"2026-06-08T22:18:23.429778Z","end":"2026-06-08T22:18:23.642193Z","steps":["trace[136694479] 'process raft request' (duration: 212.189125ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:18:23.642463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.611094ms","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-08T22:18:23.642515Z","caller":"traceutil/trace.go:171","msg":"trace[1695520305] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2260; }","duration":"187.748208ms","start":"2026-06-08T22:18:23.454752Z","end":"2026-06-08T22:18:23.6425Z","steps":["trace[1695520305] 'agreement among raft nodes before linearized reading' (duration: 187.495152ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:18:23.642495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.939733ms","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-08T22:18:23.642748Z","caller":"traceutil/trace.go:171","msg":"trace[1903437683] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2260; }","duration":"134.263041ms","start":"2026-06-08T22:18:23.508449Z","end":"2026-06-08T22:18:23.642712Z","steps":["trace[1903437683] 'agreement among raft nodes before linearized reading' (duration: 133.865611ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:18:23.875044Z","caller":"traceutil/trace.go:171","msg":"trace[988241183] linearizableReadLoop","detail":"{readStateIndex:2344; appliedIndex:2343; }","duration":"202.538804ms","start":"2026-06-08T22:18:23.672473Z","end":"2026-06-08T22:18:23.875011Z","steps":["trace[988241183] 'read index received' (duration: 109.237648ms)","trace[988241183] 'applied index is now lower than readState.Index' (duration: 93.299656ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:18:23.875082Z","caller":"traceutil/trace.go:171","msg":"trace[455396884] transaction","detail":"{read_only:false; response_revision:2261; number_of_response:1; }","duration":"227.176357ms","start":"2026-06-08T22:18:23.64786Z","end":"2026-06-08T22:18:23.875036Z","steps":["trace[455396884] 'process raft request' (duration: 133.85127ms)","trace[455396884] 'compare' (duration: 93.130652ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:18:23.875315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.82783ms","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-08T22:18:23.875377Z","caller":"traceutil/trace.go:171","msg":"trace[660310695] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2261; }","duration":"202.952734ms","start":"2026-06-08T22:18:23.67241Z","end":"2026-06-08T22:18:23.875363Z","steps":["trace[660310695] 'agreement among raft nodes before linearized reading' (duration: 202.730709ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:18:24.023363Z","caller":"traceutil/trace.go:171","msg":"trace[626467729] transaction","detail":"{read_only:false; response_revision:2262; number_of_response:1; }","duration":"143.495684ms","start":"2026-06-08T22:18:23.879834Z","end":"2026-06-08T22:18:24.02333Z","steps":["trace[626467729] 'process raft request' (duration: 110.694644ms)","trace[626467729] 'compare' (duration: 32.658295ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:18:24.106702Z","caller":"traceutil/trace.go:171","msg":"trace[158233316] transaction","detail":"{read_only:false; response_revision:2263; number_of_response:1; }","duration":"226.717285ms","start":"2026-06-08T22:18:23.879955Z","end":"2026-06-08T22:18:24.106672Z","steps":["trace[158233316] 'process raft request' (duration: 226.431908ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:18:24.106751Z","caller":"traceutil/trace.go:171","msg":"trace[928338751] linearizableReadLoop","detail":"{readStateIndex:2348; appliedIndex:2344; }","duration":"186.906946ms","start":"2026-06-08T22:18:23.919812Z","end":"2026-06-08T22:18:24.106719Z","steps":["trace[928338751] 'read index received' (duration: 70.785273ms)","trace[928338751] 'applied index is now lower than readState.Index' (duration: 116.120083ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:18:24.106908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.078211ms","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-08T22:18:24.106895Z","caller":"traceutil/trace.go:171","msg":"trace[1749944371] transaction","detail":"{read_only:false; response_revision:2265; number_of_response:1; }","duration":"226.320655ms","start":"2026-06-08T22:18:23.880508Z","end":"2026-06-08T22:18:24.106828Z","steps":["trace[1749944371] 'process raft request' (duration: 226.136411ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:18:24.106835Z","caller":"traceutil/trace.go:171","msg":"trace[1350906852] transaction","detail":"{read_only:false; response_revision:2264; number_of_response:1; }","duration":"226.427587ms","start":"2026-06-08T22:18:23.880352Z","end":"2026-06-08T22:18:24.10678Z","steps":["trace[1350906852] 'process raft request' (duration: 226.218852ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:18:24.106992Z","caller":"traceutil/trace.go:171","msg":"trace[785427565] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2265; }","duration":"187.208113ms","start":"2026-06-08T22:18:23.919766Z","end":"2026-06-08T22:18:24.106974Z","steps":["trace[785427565] 'agreement among raft nodes before linearized reading' (duration: 187.05734ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:18:24.108274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.38566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:18:24.108352Z","caller":"traceutil/trace.go:171","msg":"trace[1267884299] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:2266; }","duration":"175.581575ms","start":"2026-06-08T22:18:23.932751Z","end":"2026-06-08T22:18:24.108332Z","steps":["trace[1267884299] 'agreement among raft nodes before linearized reading' (duration: 175.340679ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:19:22.352923Z","caller":"traceutil/trace.go:171","msg":"trace[562967860] transaction","detail":"{read_only:false; response_revision:2746; number_of_response:1; }","duration":"189.371555ms","start":"2026-06-08T22:19:22.163521Z","end":"2026-06-08T22:19:22.352892Z","steps":["trace[562967860] 'process raft request' (duration: 189.17861ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:19:52.902382Z","caller":"traceutil/trace.go:171","msg":"trace[222003346] transaction","detail":"{read_only:false; response_revision:2935; number_of_response:1; }","duration":"213.80296ms","start":"2026-06-08T22:19:52.688552Z","end":"2026-06-08T22:19:52.902355Z","steps":["trace[222003346] 'process raft request' (duration: 213.610376ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:19:52.902538Z","caller":"traceutil/trace.go:171","msg":"trace[595416289] transaction","detail":"{read_only:false; response_revision:2936; number_of_response:1; }","duration":"213.451394ms","start":"2026-06-08T22:19:52.689049Z","end":"2026-06-08T22:19:52.9025Z","steps":["trace[595416289] 'process raft request' (duration: 213.261368ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:22:34.559907Z","caller":"traceutil/trace.go:171","msg":"trace[62006537] transaction","detail":"{read_only:false; response_revision:3984; number_of_response:1; }","duration":"158.52755ms","start":"2026-06-08T22:22:34.401349Z","end":"2026-06-08T22:22:34.559876Z","steps":["trace[62006537] 'process raft request' (duration: 158.297795ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:23:58.206714Z","caller":"traceutil/trace.go:171","msg":"trace[675325965] linearizableReadLoop","detail":"{readStateIndex:5137; appliedIndex:5136; }","duration":"173.210526ms","start":"2026-06-08T22:23:58.033469Z","end":"2026-06-08T22:23:58.20668Z","steps":["trace[675325965] 'read index received' (duration: 134.694923ms)","trace[675325965] 'applied index is now lower than readState.Index' (duration: 38.514213ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:23:58.206861Z","caller":"traceutil/trace.go:171","msg":"trace[1556565966] transaction","detail":"{read_only:false; response_revision:4950; number_of_response:1; }","duration":"173.38605ms","start":"2026-06-08T22:23:58.033394Z","end":"2026-06-08T22:23:58.20678Z","steps":["trace[1556565966] 'process raft request' (duration: 134.753284ms)","trace[1556565966] 'compare' (duration: 38.259067ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:23:58.207979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.437206ms","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-08T22:23:58.208112Z","caller":"traceutil/trace.go:171","msg":"trace[1262493612] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4950; }","duration":"174.633941ms","start":"2026-06-08T22:23:58.033449Z","end":"2026-06-08T22:23:58.208083Z","steps":["trace[1262493612] 'agreement among raft nodes before linearized reading' (duration: 173.438162ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:23:58.219081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.07808ms","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-08T22:23:58.219171Z","caller":"traceutil/trace.go:171","msg":"trace[950011043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4951; }","duration":"146.225233ms","start":"2026-06-08T22:23:58.072931Z","end":"2026-06-08T22:23:58.219156Z","steps":["trace[950011043] 'agreement among raft nodes before linearized reading' (duration: 145.891804ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:23:58.219416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.469912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:22 size:526781"} {"level":"info","ts":"2026-06-08T22:23:58.21953Z","caller":"traceutil/trace.go:171","msg":"trace[1818285923] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:22; response_revision:4951; }","duration":"168.719248ms","start":"2026-06-08T22:23:58.050794Z","end":"2026-06-08T22:23:58.219513Z","steps":["trace[1818285923] 'agreement among raft nodes before linearized reading' (duration: 168.029311ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:23:59.728225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.127993ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-08T22:23:59.728357Z","caller":"traceutil/trace.go:171","msg":"trace[275842276] linearizableReadLoop","detail":"{readStateIndex:5175; appliedIndex:5174; }","duration":"113.438607ms","start":"2026-06-08T22:23:59.614896Z","end":"2026-06-08T22:23:59.728335Z","steps":["trace[275842276] 'read index received' (duration: 11.162051ms)","trace[275842276] 'applied index is now lower than readState.Index' (duration: 102.274686ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:23:59.728696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.766505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8d5598fa-81e0-4431-89fd-9574c772eec9\" ","response":"range_response_count:1 size:3759"} {"level":"info","ts":"2026-06-08T22:23:59.728829Z","caller":"traceutil/trace.go:171","msg":"trace[848696967] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8d5598fa-81e0-4431-89fd-9574c772eec9; range_end:; response_count:1; response_revision:4987; }","duration":"113.95261ms","start":"2026-06-08T22:23:59.614848Z","end":"2026-06-08T22:23:59.728801Z","steps":["trace[848696967] 'agreement among raft nodes before linearized reading' (duration: 113.586941ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:24:04.408818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.334376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-npvrm\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-06-08T22:24:04.408905Z","caller":"traceutil/trace.go:171","msg":"trace[1318082057] range","detail":"{range_begin:/registry/pods/openstack/keepalived-npvrm; range_end:; response_count:1; response_revision:5143; }","duration":"144.455769ms","start":"2026-06-08T22:24:04.26443Z","end":"2026-06-08T22:24:04.408885Z","steps":["trace[1318082057] 'range keys from in-memory index tree' (duration: 144.10964ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:24:04.408924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.712288ms","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-08T22:24:04.409016Z","caller":"traceutil/trace.go:171","msg":"trace[1224532449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5143; }","duration":"152.904014ms","start":"2026-06-08T22:24:04.25609Z","end":"2026-06-08T22:24:04.408994Z","steps":["trace[1224532449] 'range keys from in-memory index tree' (duration: 152.476713ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:24:04.635118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.826642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:23 size:724440"} {"level":"info","ts":"2026-06-08T22:24:04.635207Z","caller":"traceutil/trace.go:171","msg":"trace[1782551113] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:23; response_revision:5147; }","duration":"121.971665ms","start":"2026-06-08T22:24:04.513215Z","end":"2026-06-08T22:24:04.635187Z","steps":["trace[1782551113] 'range keys from in-memory index tree' (duration: 121.31662ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:24:04.645434Z","caller":"traceutil/trace.go:171","msg":"trace[687063595] transaction","detail":"{read_only:false; response_revision:5148; number_of_response:1; }","duration":"110.699932ms","start":"2026-06-08T22:24:04.5347Z","end":"2026-06-08T22:24:04.6454Z","steps":["trace[687063595] 'process raft request' (duration: 110.519807ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:24:04.651288Z","caller":"traceutil/trace.go:171","msg":"trace[511551620] transaction","detail":"{read_only:false; response_revision:5149; number_of_response:1; }","duration":"115.14767ms","start":"2026-06-08T22:24:04.536094Z","end":"2026-06-08T22:24:04.651242Z","steps":["trace[511551620] 'process raft request' (duration: 114.952276ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:24:04.842534Z","caller":"traceutil/trace.go:171","msg":"trace[1803681768] transaction","detail":"{read_only:false; response_revision:5151; number_of_response:1; }","duration":"127.842607ms","start":"2026-06-08T22:24:04.714659Z","end":"2026-06-08T22:24:04.842502Z","steps":["trace[1803681768] 'process raft request' (duration: 121.396361ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:24:18.647659Z","caller":"traceutil/trace.go:171","msg":"trace[1879925824] transaction","detail":"{read_only:false; response_revision:5441; number_of_response:1; }","duration":"142.092272ms","start":"2026-06-08T22:24:18.505544Z","end":"2026-06-08T22:24:18.647636Z","steps":["trace[1879925824] 'process raft request' (duration: 141.969259ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:24:18.647671Z","caller":"traceutil/trace.go:171","msg":"trace[492902241] linearizableReadLoop","detail":"{readStateIndex:5644; appliedIndex:5643; }","duration":"139.470409ms","start":"2026-06-08T22:24:18.508159Z","end":"2026-06-08T22:24:18.647629Z","steps":["trace[492902241] 'read index received' (duration: 139.455359ms)","trace[492902241] 'applied index is now lower than readState.Index' (duration: 12µs)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:24:18.647987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.799527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:131149"} {"level":"info","ts":"2026-06-08T22:24:18.648074Z","caller":"traceutil/trace.go:171","msg":"trace[199200514] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:5441; }","duration":"139.909899ms","start":"2026-06-08T22:24:18.508144Z","end":"2026-06-08T22:24:18.648053Z","steps":["trace[199200514] 'agreement among raft nodes before linearized reading' (duration: 139.590882ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:24:18.648057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.236369ms","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-08T22:24:18.648165Z","caller":"traceutil/trace.go:171","msg":"trace[553569216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5441; }","duration":"131.372122ms","start":"2026-06-08T22:24:18.516773Z","end":"2026-06-08T22:24:18.648145Z","steps":["trace[553569216] 'agreement among raft nodes before linearized reading' (duration: 131.102476ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:24:18.648073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.126079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-npvrm\" ","response":"range_response_count:1 size:6520"} {"level":"warn","ts":"2026-06-08T22:24:18.648143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.119743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-08T22:24:18.648239Z","caller":"traceutil/trace.go:171","msg":"trace[263172838] range","detail":"{range_begin:/registry/pods/openstack/keepalived-npvrm; range_end:; response_count:1; response_revision:5441; }","duration":"134.317504ms","start":"2026-06-08T22:24:18.513902Z","end":"2026-06-08T22:24:18.648219Z","steps":["trace[263172838] 'agreement among raft nodes before linearized reading' (duration: 133.994007ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:24:18.648251Z","caller":"traceutil/trace.go:171","msg":"trace[200647829] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5441; }","duration":"135.327928ms","start":"2026-06-08T22:24:18.512907Z","end":"2026-06-08T22:24:18.648235Z","steps":["trace[200647829] 'agreement among raft nodes before linearized reading' (duration: 134.957919ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:24:18.771199Z","caller":"traceutil/trace.go:171","msg":"trace[827327215] linearizableReadLoop","detail":"{readStateIndex:5645; appliedIndex:5644; }","duration":"118.047439ms","start":"2026-06-08T22:24:18.653122Z","end":"2026-06-08T22:24:18.771169Z","steps":["trace[827327215] 'read index received' (duration: 117.777493ms)","trace[827327215] 'applied index is now lower than readState.Index' (duration: 268.546µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:24:18.771248Z","caller":"traceutil/trace.go:171","msg":"trace[577724806] transaction","detail":"{read_only:false; response_revision:5442; number_of_response:1; }","duration":"119.370141ms","start":"2026-06-08T22:24:18.651845Z","end":"2026-06-08T22:24:18.771215Z","steps":["trace[577724806] 'process raft request' (duration: 119.073244ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:24:18.771581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.429078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:70771"} {"level":"info","ts":"2026-06-08T22:24:18.771643Z","caller":"traceutil/trace.go:171","msg":"trace[1691333956] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:5442; }","duration":"118.560901ms","start":"2026-06-08T22:24:18.653068Z","end":"2026-06-08T22:24:18.771628Z","steps":["trace[1691333956] 'agreement among raft nodes before linearized reading' (duration: 118.217173ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:24:18.842096Z","caller":"traceutil/trace.go:171","msg":"trace[1000286509] transaction","detail":"{read_only:false; response_revision:5443; number_of_response:1; }","duration":"188.615668ms","start":"2026-06-08T22:24:18.653359Z","end":"2026-06-08T22:24:18.841975Z","steps":["trace[1000286509] 'process raft request' (duration: 188.207449ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:24:18.842322Z","caller":"traceutil/trace.go:171","msg":"trace[1847867928] transaction","detail":"{read_only:false; response_revision:5445; number_of_response:1; }","duration":"140.78542ms","start":"2026-06-08T22:24:18.701509Z","end":"2026-06-08T22:24:18.842294Z","steps":["trace[1847867928] 'process raft request' (duration: 140.653207ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:24:18.842355Z","caller":"traceutil/trace.go:171","msg":"trace[928002693] transaction","detail":"{read_only:false; response_revision:5444; number_of_response:1; }","duration":"188.344872ms","start":"2026-06-08T22:24:18.653967Z","end":"2026-06-08T22:24:18.842312Z","steps":["trace[928002693] 'process raft request' (duration: 187.948823ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:24:27.62925Z","caller":"traceutil/trace.go:171","msg":"trace[1986343581] transaction","detail":"{read_only:false; response_revision:5615; number_of_response:1; }","duration":"145.478224ms","start":"2026-06-08T22:24:27.483737Z","end":"2026-06-08T22:24:27.629215Z","steps":["trace[1986343581] 'process raft request' (duration: 102.846282ms)","trace[1986343581] 'compare' (duration: 42.252613ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:24:27.970961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.023696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-08T22:24:27.971468Z","caller":"traceutil/trace.go:171","msg":"trace[933502935] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:5615; }","duration":"172.19606ms","start":"2026-06-08T22:24:27.798832Z","end":"2026-06-08T22:24:27.971028Z","steps":["trace[933502935] 'count revisions from in-memory index tree' (duration: 171.886333ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:25:01.274527Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2980} {"level":"info","ts":"2026-06-08T22:25:01.348509Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2980,"took":"72.312405ms","hash":3319191187,"current-db-size-bytes":57081856,"current-db-size":"57 MB","current-db-size-in-use-bytes":32350208,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-08T22:25:01.348576Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3319191187,"revision":2980,"compact-revision":-1} {"level":"info","ts":"2026-06-08T22:30:01.282104Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5915} {"level":"info","ts":"2026-06-08T22:30:01.366985Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5915,"took":"81.5197ms","hash":4282490773,"current-db-size-bytes":57081856,"current-db-size":"57 MB","current-db-size-in-use-bytes":26034176,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-08T22:30:01.367049Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4282490773,"revision":5915,"compact-revision":2980} {"level":"info","ts":"2026-06-08T22:30:49.282719Z","caller":"traceutil/trace.go:171","msg":"trace[153274833] transaction","detail":"{read_only:false; response_revision:8531; number_of_response:1; }","duration":"161.397631ms","start":"2026-06-08T22:30:49.121301Z","end":"2026-06-08T22:30:49.282698Z","steps":["trace[153274833] 'process raft request' (duration: 161.269028ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:30:49.525689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.547133ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-08T22:30:49.525811Z","caller":"traceutil/trace.go:171","msg":"trace[589473764] linearizableReadLoop","detail":"{readStateIndex:8833; appliedIndex:8831; }","duration":"373.434508ms","start":"2026-06-08T22:30:49.15236Z","end":"2026-06-08T22:30:49.525794Z","steps":["trace[589473764] 'read index received' (duration: 130.260401ms)","trace[589473764] 'applied index is now lower than readState.Index' (duration: 243.172437ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:30:49.525871Z","caller":"traceutil/trace.go:171","msg":"trace[2076446079] transaction","detail":"{read_only:false; response_revision:8532; number_of_response:1; }","duration":"403.112852ms","start":"2026-06-08T22:30:49.12273Z","end":"2026-06-08T22:30:49.525843Z","steps":["trace[2076446079] 'process raft request' (duration: 273.316442ms)","trace[2076446079] 'compare' (duration: 129.173934ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:30:49.526058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:30:49.122715Z","time spent":"403.260995ms","remote":"127.0.0.1:36224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:30:49.526059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.697784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-npvrm\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-06-08T22:30:49.526109Z","caller":"traceutil/trace.go:171","msg":"trace[1146757212] range","detail":"{range_begin:/registry/pods/openstack/keepalived-npvrm; range_end:; response_count:1; response_revision:8532; }","duration":"373.795996ms","start":"2026-06-08T22:30:49.152302Z","end":"2026-06-08T22:30:49.526098Z","steps":["trace[1146757212] 'agreement among raft nodes before linearized reading' (duration: 373.583291ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:30:49.526152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:30:49.15228Z","time spent":"373.864148ms","remote":"127.0.0.1:36142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6542,"request content":"key:\"/registry/pods/openstack/keepalived-npvrm\" "} {"level":"warn","ts":"2026-06-08T22:30:49.52631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.209617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:30:49.526356Z","caller":"traceutil/trace.go:171","msg":"trace[1300406466] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8532; }","duration":"340.303209ms","start":"2026-06-08T22:30:49.186042Z","end":"2026-06-08T22:30:49.526345Z","steps":["trace[1300406466] 'agreement among raft nodes before linearized reading' (duration: 340.227867ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:30:49.526389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:30:49.186019Z","time spent":"340.36091ms","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-06-08T22:31:26.025494Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002374.wal"} {"level":"info","ts":"2026-06-08T22:31:30.084084Z","caller":"traceutil/trace.go:171","msg":"trace[1079658744] linearizableReadLoop","detail":"{readStateIndex:9113; appliedIndex:9112; }","duration":"258.536463ms","start":"2026-06-08T22:31:29.825514Z","end":"2026-06-08T22:31:30.084051Z","steps":["trace[1079658744] 'read index received' (duration: 258.375019ms)","trace[1079658744] 'applied index is now lower than readState.Index' (duration: 159.994µs)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:31:30.084342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.81046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-npvrm\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-06-08T22:31:30.084388Z","caller":"traceutil/trace.go:171","msg":"trace[475133463] range","detail":"{range_begin:/registry/pods/openstack/keepalived-npvrm; range_end:; response_count:1; response_revision:8801; }","duration":"258.926134ms","start":"2026-06-08T22:31:29.82545Z","end":"2026-06-08T22:31:30.084376Z","steps":["trace[475133463] 'agreement among raft nodes before linearized reading' (duration: 258.731609ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:31:30.084271Z","caller":"traceutil/trace.go:171","msg":"trace[1843662174] transaction","detail":"{read_only:false; response_revision:8801; number_of_response:1; }","duration":"275.967138ms","start":"2026-06-08T22:31:29.808148Z","end":"2026-06-08T22:31:30.084115Z","steps":["trace[1843662174] 'process raft request' (duration: 275.703861ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:31:30.13634Z","caller":"traceutil/trace.go:171","msg":"trace[1690034489] transaction","detail":"{read_only:false; response_revision:8802; number_of_response:1; }","duration":"266.721552ms","start":"2026-06-08T22:31:29.869583Z","end":"2026-06-08T22:31:30.136305Z","steps":["trace[1690034489] 'process raft request' (duration: 231.541395ms)","trace[1690034489] 'compare' (duration: 34.88461ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:31:42.876287Z","caller":"traceutil/trace.go:171","msg":"trace[1378281635] transaction","detail":"{read_only:false; response_revision:8907; number_of_response:1; }","duration":"110.110424ms","start":"2026-06-08T22:31:42.76615Z","end":"2026-06-08T22:31:42.876261Z","steps":["trace[1378281635] 'process raft request' (duration: 109.91407ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:31:43.025521Z","caller":"traceutil/trace.go:171","msg":"trace[1762029508] linearizableReadLoop","detail":"{readStateIndex:9225; appliedIndex:9224; }","duration":"216.425956ms","start":"2026-06-08T22:31:42.809074Z","end":"2026-06-08T22:31:43.0255Z","steps":["trace[1762029508] 'read index received' (duration: 67.903755ms)","trace[1762029508] 'applied index is now lower than readState.Index' (duration: 148.521031ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:31:43.025515Z","caller":"traceutil/trace.go:171","msg":"trace[1504624834] transaction","detail":"{read_only:false; response_revision:8908; number_of_response:1; }","duration":"259.24401ms","start":"2026-06-08T22:31:42.766211Z","end":"2026-06-08T22:31:43.025455Z","steps":["trace[1504624834] 'process raft request' (duration: 250.453385ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:31:43.025563Z","caller":"traceutil/trace.go:171","msg":"trace[14587038] transaction","detail":"{read_only:false; response_revision:8909; number_of_response:1; }","duration":"151.731699ms","start":"2026-06-08T22:31:42.873792Z","end":"2026-06-08T22:31:43.025524Z","steps":["trace[14587038] 'process raft request' (duration: 151.424102ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:31:43.025713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.632641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-08T22:31:43.025767Z","caller":"traceutil/trace.go:171","msg":"trace[1528845797] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:8909; }","duration":"216.710083ms","start":"2026-06-08T22:31:42.809044Z","end":"2026-06-08T22:31:43.025754Z","steps":["trace[1528845797] 'agreement among raft nodes before linearized reading' (duration: 216.556669ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:31:43.041252Z","caller":"traceutil/trace.go:171","msg":"trace[1706606341] transaction","detail":"{read_only:false; response_revision:8910; number_of_response:1; }","duration":"140.73425ms","start":"2026-06-08T22:31:42.90049Z","end":"2026-06-08T22:31:43.041224Z","steps":["trace[1706606341] 'process raft request' (duration: 140.440053ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:31:43.041745Z","caller":"traceutil/trace.go:171","msg":"trace[1950314721] transaction","detail":"{read_only:false; response_revision:8911; number_of_response:1; }","duration":"140.820563ms","start":"2026-06-08T22:31:42.900897Z","end":"2026-06-08T22:31:43.041718Z","steps":["trace[1950314721] 'process raft request' (duration: 140.2679ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:31:43.041962Z","caller":"traceutil/trace.go:171","msg":"trace[931825955] transaction","detail":"{read_only:false; response_revision:8912; number_of_response:1; }","duration":"129.248431ms","start":"2026-06-08T22:31:42.912694Z","end":"2026-06-08T22:31:43.041943Z","steps":["trace[931825955] 'process raft request' (duration: 128.873442ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:31:43.042272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.316322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-f7dhm\" ","response":"range_response_count:1 size:4647"} {"level":"info","ts":"2026-06-08T22:31:43.04239Z","caller":"traceutil/trace.go:171","msg":"trace[550562424] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-f7dhm; range_end:; response_count:1; response_revision:8913; }","duration":"170.477306ms","start":"2026-06-08T22:31:42.871894Z","end":"2026-06-08T22:31:43.042371Z","steps":["trace[550562424] 'agreement among raft nodes before linearized reading' (duration: 170.067296ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:31:43.042818Z","caller":"traceutil/trace.go:171","msg":"trace[2123389215] transaction","detail":"{read_only:false; response_revision:8913; number_of_response:1; }","duration":"127.803726ms","start":"2026-06-08T22:31:42.914175Z","end":"2026-06-08T22:31:43.041979Z","steps":["trace[2123389215] 'process raft request' (duration: 127.605112ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:31:57.109073Z","caller":"traceutil/trace.go:171","msg":"trace[173733752] transaction","detail":"{read_only:false; response_revision:9024; number_of_response:1; }","duration":"102.456259ms","start":"2026-06-08T22:31:57.006583Z","end":"2026-06-08T22:31:57.109039Z","steps":["trace[173733752] 'process raft request' (duration: 102.276903ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:32:51.483887Z","caller":"traceutil/trace.go:171","msg":"trace[2041619264] transaction","detail":"{read_only:false; response_revision:9613; number_of_response:1; }","duration":"145.138669ms","start":"2026-06-08T22:32:51.338719Z","end":"2026-06-08T22:32:51.483858Z","steps":["trace[2041619264] 'process raft request' (duration: 144.911913ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:32:51.484284Z","caller":"traceutil/trace.go:171","msg":"trace[823407982] transaction","detail":"{read_only:false; response_revision:9615; number_of_response:1; }","duration":"136.243622ms","start":"2026-06-08T22:32:51.348024Z","end":"2026-06-08T22:32:51.484267Z","steps":["trace[823407982] 'process raft request' (duration: 136.104159ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:32:51.484284Z","caller":"traceutil/trace.go:171","msg":"trace[554335791] transaction","detail":"{read_only:false; response_revision:9614; number_of_response:1; }","duration":"139.613325ms","start":"2026-06-08T22:32:51.344639Z","end":"2026-06-08T22:32:51.484252Z","steps":["trace[554335791] 'process raft request' (duration: 139.141713ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:32:51.484262Z","caller":"traceutil/trace.go:171","msg":"trace[2114978443] linearizableReadLoop","detail":"{readStateIndex:9952; appliedIndex:9948; }","duration":"111.530739ms","start":"2026-06-08T22:32:51.372705Z","end":"2026-06-08T22:32:51.484236Z","steps":["trace[2114978443] 'read index received' (duration: 30.262318ms)","trace[2114978443] 'applied index is now lower than readState.Index' (duration: 81.266681ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:32:51.484487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.778185ms","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-08T22:32:51.484535Z","caller":"traceutil/trace.go:171","msg":"trace[46618440] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9615; }","duration":"111.902508ms","start":"2026-06-08T22:32:51.372621Z","end":"2026-06-08T22:32:51.484524Z","steps":["trace[46618440] 'agreement among raft nodes before linearized reading' (duration: 111.695333ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:32:59.511831Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"50e03cebfd94cbba","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-08T22:32:59.518268Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-08T22:32:59.518448Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-08T22:33:04.400105Z","caller":"traceutil/trace.go:171","msg":"trace[954341521] linearizableReadLoop","detail":"{readStateIndex:10026; appliedIndex:10025; }","duration":"213.954105ms","start":"2026-06-08T22:33:04.186114Z","end":"2026-06-08T22:33:04.400068Z","steps":["trace[954341521] 'read index received' (duration: 213.72283ms)","trace[954341521] 'applied index is now lower than readState.Index' (duration: 229.435µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:33:04.400249Z","caller":"traceutil/trace.go:171","msg":"trace[2125358409] transaction","detail":"{read_only:false; response_revision:9687; number_of_response:1; }","duration":"252.025935ms","start":"2026-06-08T22:33:04.148181Z","end":"2026-06-08T22:33:04.400206Z","steps":["trace[2125358409] 'process raft request' (duration: 251.683406ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:33:04.400388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.248943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-08T22:33:04.400443Z","caller":"traceutil/trace.go:171","msg":"trace[66530894] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:9687; }","duration":"214.381766ms","start":"2026-06-08T22:33:04.186048Z","end":"2026-06-08T22:33:04.40043Z","steps":["trace[66530894] 'agreement among raft nodes before linearized reading' (duration: 214.154271ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:35:01.289929Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8080} {"level":"info","ts":"2026-06-08T22:35:01.359107Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8080,"took":"65.376534ms","hash":1808994449,"current-db-size-bytes":57081856,"current-db-size":"57 MB","current-db-size-in-use-bytes":28147712,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-08T22:35:01.359204Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1808994449,"revision":8080,"compact-revision":5915} {"level":"info","ts":"2026-06-08T22:38:39.427889Z","caller":"traceutil/trace.go:171","msg":"trace[1838443181] linearizableReadLoop","detail":"{readStateIndex:12518; appliedIndex:12517; }","duration":"112.454798ms","start":"2026-06-08T22:38:39.315401Z","end":"2026-06-08T22:38:39.427856Z","steps":["trace[1838443181] 'read index received' (duration: 112.280834ms)","trace[1838443181] 'applied index is now lower than readState.Index' (duration: 172.554µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:38:39.42803Z","caller":"traceutil/trace.go:171","msg":"trace[1571473280] transaction","detail":"{read_only:false; response_revision:12102; number_of_response:1; }","duration":"113.162546ms","start":"2026-06-08T22:38:39.314828Z","end":"2026-06-08T22:38:39.427991Z","steps":["trace[1571473280] 'process raft request' (duration: 112.858778ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:38:39.428274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.823367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-06-08T22:38:39.428429Z","caller":"traceutil/trace.go:171","msg":"trace[395725464] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:12102; }","duration":"113.020492ms","start":"2026-06-08T22:38:39.315381Z","end":"2026-06-08T22:38:39.428401Z","steps":["trace[395725464] 'agreement among raft nodes before linearized reading' (duration: 112.631122ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:38:39.428548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.277306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-06-08T22:38:39.428606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.0713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-06-08T22:38:39.428687Z","caller":"traceutil/trace.go:171","msg":"trace[2107422806] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:12102; }","duration":"108.464061ms","start":"2026-06-08T22:38:39.320169Z","end":"2026-06-08T22:38:39.428634Z","steps":["trace[2107422806] 'agreement among raft nodes before linearized reading' (duration: 108.062752ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:38:39.428793Z","caller":"traceutil/trace.go:171","msg":"trace[763408924] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12102; }","duration":"103.161411ms","start":"2026-06-08T22:38:39.325483Z","end":"2026-06-08T22:38:39.428644Z","steps":["trace[763408924] 'agreement among raft nodes before linearized reading' (duration: 102.821143ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:38:40.024612Z","caller":"traceutil/trace.go:171","msg":"trace[394535320] transaction","detail":"{read_only:false; response_revision:12104; number_of_response:1; }","duration":"158.295675ms","start":"2026-06-08T22:38:39.866283Z","end":"2026-06-08T22:38:40.024578Z","steps":["trace[394535320] 'process raft request' (duration: 158.048589ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:38:40.632322Z","caller":"traceutil/trace.go:171","msg":"trace[764016120] linearizableReadLoop","detail":"{readStateIndex:12524; appliedIndex:12523; }","duration":"135.207702ms","start":"2026-06-08T22:38:40.497094Z","end":"2026-06-08T22:38:40.632302Z","steps":["trace[764016120] 'read index received' (duration: 135.007748ms)","trace[764016120] 'applied index is now lower than readState.Index' (duration: 199.194µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:38:40.63252Z","caller":"traceutil/trace.go:171","msg":"trace[461626051] transaction","detail":"{read_only:false; response_revision:12107; number_of_response:1; }","duration":"341.141058ms","start":"2026-06-08T22:38:40.291234Z","end":"2026-06-08T22:38:40.632375Z","steps":["trace[461626051] 'process raft request' (duration: 340.934133ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:38:40.632776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:38:40.291205Z","time spent":"341.450335ms","remote":"127.0.0.1:36224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:38:40.632755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.611503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-08T22:38:40.632907Z","caller":"traceutil/trace.go:171","msg":"trace[1295351059] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12107; }","duration":"135.857858ms","start":"2026-06-08T22:38:40.497024Z","end":"2026-06-08T22:38:40.632882Z","steps":["trace[1295351059] 'agreement among raft nodes before linearized reading' (duration: 135.366707ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:40:01.299794Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10510} {"level":"info","ts":"2026-06-08T22:40:01.399189Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10510,"took":"95.23953ms","hash":111645866,"current-db-size-bytes":57081856,"current-db-size":"57 MB","current-db-size-in-use-bytes":25841664,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-08T22:40:01.399273Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":111645866,"revision":10510,"compact-revision":8080} {"level":"info","ts":"2026-06-08T22:44:46.47049Z","caller":"traceutil/trace.go:171","msg":"trace[1493432415] transaction","detail":"{read_only:false; response_revision:14576; number_of_response:1; }","duration":"159.775411ms","start":"2026-06-08T22:44:46.310688Z","end":"2026-06-08T22:44:46.470464Z","steps":["trace[1493432415] 'process raft request' (duration: 159.577966ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:44:46.509412Z","caller":"traceutil/trace.go:171","msg":"trace[766251665] transaction","detail":"{read_only:false; response_revision:14577; number_of_response:1; }","duration":"182.566928ms","start":"2026-06-08T22:44:46.326806Z","end":"2026-06-08T22:44:46.509373Z","steps":["trace[766251665] 'process raft request' (duration: 181.846501ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:44:46.509652Z","caller":"traceutil/trace.go:171","msg":"trace[1254988129] transaction","detail":"{read_only:false; response_revision:14578; number_of_response:1; }","duration":"180.973968ms","start":"2026-06-08T22:44:46.328666Z","end":"2026-06-08T22:44:46.50964Z","steps":["trace[1254988129] 'process raft request' (duration: 180.155769ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:44:46.509803Z","caller":"traceutil/trace.go:171","msg":"trace[1736739699] transaction","detail":"{read_only:false; response_revision:14579; number_of_response:1; }","duration":"176.465399ms","start":"2026-06-08T22:44:46.333326Z","end":"2026-06-08T22:44:46.509791Z","steps":["trace[1736739699] 'process raft request' (duration: 175.536877ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:45:01.306656Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12641} {"level":"info","ts":"2026-06-08T22:45:01.384222Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12641,"took":"74.36401ms","hash":415331670,"current-db-size-bytes":57081856,"current-db-size":"57 MB","current-db-size-in-use-bytes":26812416,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-08T22:45:01.384312Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":415331670,"revision":12641,"compact-revision":10510} {"level":"info","ts":"2026-06-08T22:45:26.030436Z","caller":"traceutil/trace.go:171","msg":"trace[2113625229] transaction","detail":"{read_only:false; response_revision:14804; number_of_response:1; }","duration":"131.562501ms","start":"2026-06-08T22:45:25.898835Z","end":"2026-06-08T22:45:26.030398Z","steps":["trace[2113625229] 'process raft request' (duration: 131.329475ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:45:27.194903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.604886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-qgrqz\" ","response":"range_response_count:1 size:12360"} {"level":"info","ts":"2026-06-08T22:45:27.195051Z","caller":"traceutil/trace.go:171","msg":"trace[910966862] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-qgrqz; range_end:; response_count:1; response_revision:14808; }","duration":"436.828291ms","start":"2026-06-08T22:45:26.758197Z","end":"2026-06-08T22:45:27.195025Z","steps":["trace[910966862] 'range keys from in-memory index tree' (duration: 436.32932ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:45:27.195098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:45:26.758169Z","time spent":"436.916684ms","remote":"127.0.0.1:36142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":12382,"request content":"key:\"/registry/pods/openstack/openvswitch-qgrqz\" "} {"level":"warn","ts":"2026-06-08T22:45:27.195085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.486394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-08T22:45:27.195201Z","caller":"traceutil/trace.go:171","msg":"trace[1339163441] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14808; }","duration":"271.69079ms","start":"2026-06-08T22:45:26.923483Z","end":"2026-06-08T22:45:27.195174Z","steps":["trace[1339163441] 'range keys from in-memory index tree' (duration: 271.225288ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:45:27.195222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.356897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-npvrm\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-06-08T22:45:27.195313Z","caller":"traceutil/trace.go:171","msg":"trace[2078335513] range","detail":"{range_begin:/registry/pods/openstack/keepalived-npvrm; range_end:; response_count:1; response_revision:14808; }","duration":"156.47136ms","start":"2026-06-08T22:45:27.038821Z","end":"2026-06-08T22:45:27.195292Z","steps":["trace[2078335513] 'range keys from in-memory index tree' (duration: 156.170493ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:45:27.19543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.887385ms","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-08T22:45:27.195612Z","caller":"traceutil/trace.go:171","msg":"trace[1981714397] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:14808; }","duration":"263.155681ms","start":"2026-06-08T22:45:26.932424Z","end":"2026-06-08T22:45:27.195579Z","steps":["trace[1981714397] 'count revisions from in-memory index tree' (duration: 262.786012ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:45:27.1956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.786139ms","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-08T22:45:27.195881Z","caller":"traceutil/trace.go:171","msg":"trace[1862687688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14808; }","duration":"403.109236ms","start":"2026-06-08T22:45:26.792748Z","end":"2026-06-08T22:45:27.195857Z","steps":["trace[1862687688] 'range keys from in-memory index tree' (duration: 401.835836ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:45:27.195998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:45:26.792725Z","time spent":"403.255279ms","remote":"127.0.0.1:36224","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-08T22:45:37.081717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.907461ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-08T22:45:37.081982Z","caller":"traceutil/trace.go:171","msg":"trace[671130812] linearizableReadLoop","detail":"{readStateIndex:15494; appliedIndex:15491; }","duration":"146.777162ms","start":"2026-06-08T22:45:36.935124Z","end":"2026-06-08T22:45:37.081902Z","steps":["trace[671130812] 'read index received' (duration: 28.74µs)","trace[671130812] 'applied index is now lower than readState.Index' (duration: 146.747792ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:45:37.082186Z","caller":"traceutil/trace.go:171","msg":"trace[1968874717] transaction","detail":"{read_only:false; response_revision:14977; number_of_response:1; }","duration":"359.918459ms","start":"2026-06-08T22:45:36.722219Z","end":"2026-06-08T22:45:37.082137Z","steps":["trace[1968874717] 'process raft request' (duration: 359.651433ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:45:37.082216Z","caller":"traceutil/trace.go:171","msg":"trace[1344085160] transaction","detail":"{read_only:false; response_revision:14976; number_of_response:1; }","duration":"360.409802ms","start":"2026-06-08T22:45:36.721784Z","end":"2026-06-08T22:45:37.082194Z","steps":["trace[1344085160] 'process raft request' (duration: 360.034443ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:45:37.082216Z","caller":"traceutil/trace.go:171","msg":"trace[227580481] transaction","detail":"{read_only:false; response_revision:14975; number_of_response:1; }","duration":"362.168014ms","start":"2026-06-08T22:45:36.720013Z","end":"2026-06-08T22:45:37.082181Z","steps":["trace[227580481] 'process raft request' (duration: 186.689779ms)","trace[227580481] 'compare' (duration: 174.763368ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:45:37.08238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:45:36.722208Z","time spent":"360.066903ms","remote":"127.0.0.1:36224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:45:37.082391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:45:36.721748Z","time spent":"360.541035ms","remote":"127.0.0.1:36224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:45:37.082626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.468879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1c8bcddb-b1d9-4e6b-9d6c-2b737e35ff44\" ","response":"range_response_count:1 size:3606"} {"level":"info","ts":"2026-06-08T22:45:37.082725Z","caller":"traceutil/trace.go:171","msg":"trace[1130957922] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1c8bcddb-b1d9-4e6b-9d6c-2b737e35ff44; range_end:; response_count:1; response_revision:14977; }","duration":"147.638133ms","start":"2026-06-08T22:45:36.935063Z","end":"2026-06-08T22:45:37.082701Z","steps":["trace[1130957922] 'agreement among raft nodes before linearized reading' (duration: 147.277584ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:45:37.082741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.789167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:17 size:158265"} {"level":"info","ts":"2026-06-08T22:45:37.08287Z","caller":"traceutil/trace.go:171","msg":"trace[615840454] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:17; response_revision:14977; }","duration":"129.996931ms","start":"2026-06-08T22:45:36.952845Z","end":"2026-06-08T22:45:37.082842Z","steps":["trace[615840454] 'agreement among raft nodes before linearized reading' (duration: 129.441158ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:45:37.082827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:45:36.71998Z","time spent":"362.38101ms","remote":"127.0.0.1:36224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:47:18.086606Z","caller":"traceutil/trace.go:171","msg":"trace[350382342] transaction","detail":"{read_only:false; response_revision:16069; number_of_response:1; }","duration":"137.946689ms","start":"2026-06-08T22:47:17.948607Z","end":"2026-06-08T22:47:18.086554Z","steps":["trace[350382342] 'process raft request' (duration: 137.734784ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:47:18.369791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.070809ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-08T22:47:18.369914Z","caller":"traceutil/trace.go:171","msg":"trace[348979646] linearizableReadLoop","detail":"{readStateIndex:16619; appliedIndex:16617; }","duration":"353.471338ms","start":"2026-06-08T22:47:18.016424Z","end":"2026-06-08T22:47:18.369895Z","steps":["trace[348979646] 'read index received' (duration: 69.847701ms)","trace[348979646] 'applied index is now lower than readState.Index' (duration: 283.622197ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:47:18.369985Z","caller":"traceutil/trace.go:171","msg":"trace[153314024] transaction","detail":"{read_only:false; response_revision:16070; number_of_response:1; }","duration":"419.597328ms","start":"2026-06-08T22:47:17.950349Z","end":"2026-06-08T22:47:18.369947Z","steps":["trace[153314024] 'process raft request' (duration: 163.266913ms)","trace[153314024] 'compare' (duration: 255.438704ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:47:18.370075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.959355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:47:18.370107Z","caller":"traceutil/trace.go:171","msg":"trace[334703726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16070; }","duration":"215.043306ms","start":"2026-06-08T22:47:18.155054Z","end":"2026-06-08T22:47:18.370098Z","steps":["trace[334703726] 'agreement among raft nodes before linearized reading' (duration: 214.959824ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:47:18.370163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:47:17.950322Z","time spent":"419.741972ms","remote":"127.0.0.1:36224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:47:18.370213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.439667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-08T22:47:18.37033Z","caller":"traceutil/trace.go:171","msg":"trace[558517478] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:16070; }","duration":"201.623911ms","start":"2026-06-08T22:47:18.168677Z","end":"2026-06-08T22:47:18.370301Z","steps":["trace[558517478] 'agreement among raft nodes before linearized reading' (duration: 201.410686ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:47:18.370287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.827357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-08T22:47:18.370362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.411999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-sd8rv\" ","response":"range_response_count:1 size:22088"} {"level":"info","ts":"2026-06-08T22:47:18.371075Z","caller":"traceutil/trace.go:171","msg":"trace[819917576] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-sd8rv; range_end:; response_count:1; response_revision:16070; }","duration":"174.169926ms","start":"2026-06-08T22:47:18.196873Z","end":"2026-06-08T22:47:18.371043Z","steps":["trace[819917576] 'agreement among raft nodes before linearized reading' (duration: 173.204313ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:47:18.370614Z","caller":"traceutil/trace.go:171","msg":"trace[863811020] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16070; }","duration":"354.005731ms","start":"2026-06-08T22:47:18.016387Z","end":"2026-06-08T22:47:18.370392Z","steps":["trace[863811020] 'agreement among raft nodes before linearized reading' (duration: 353.592572ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:47:18.37126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:47:18.016374Z","time spent":"354.866662ms","remote":"127.0.0.1:36224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-08T22:47:18.370804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.112617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-sd8rv\" ","response":"range_response_count:1 size:22088"} {"level":"info","ts":"2026-06-08T22:47:18.371391Z","caller":"traceutil/trace.go:171","msg":"trace[1948457801] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-sd8rv; range_end:; response_count:1; response_revision:16070; }","duration":"165.692931ms","start":"2026-06-08T22:47:18.20566Z","end":"2026-06-08T22:47:18.371353Z","steps":["trace[1948457801] 'agreement among raft nodes before linearized reading' (duration: 164.997345ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:47:24.621354Z","caller":"traceutil/trace.go:171","msg":"trace[540186581] transaction","detail":"{read_only:false; response_revision:16102; number_of_response:1; }","duration":"215.483447ms","start":"2026-06-08T22:47:24.405844Z","end":"2026-06-08T22:47:24.621327Z","steps":["trace[540186581] 'process raft request' (duration: 215.307243ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:47:24.846116Z","caller":"traceutil/trace.go:171","msg":"trace[354527193] transaction","detail":"{read_only:false; response_revision:16103; number_of_response:1; }","duration":"440.117565ms","start":"2026-06-08T22:47:24.405959Z","end":"2026-06-08T22:47:24.846077Z","steps":["trace[354527193] 'process raft request' (duration: 355.082227ms)","trace[354527193] 'compare' (duration: 84.753901ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:47:24.846269Z","caller":"traceutil/trace.go:171","msg":"trace[410690704] linearizableReadLoop","detail":"{readStateIndex:16655; appliedIndex:16652; }","duration":"391.97675ms","start":"2026-06-08T22:47:24.454254Z","end":"2026-06-08T22:47:24.84623Z","steps":["trace[410690704] 'read index received' (duration: 166.916212ms)","trace[410690704] 'applied index is now lower than readState.Index' (duration: 225.058148ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:47:24.846352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:47:24.405944Z","time spent":"440.288679ms","remote":"127.0.0.1:36224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:47:24.846324Z","caller":"traceutil/trace.go:171","msg":"trace[792182930] transaction","detail":"{read_only:false; response_revision:16104; number_of_response:1; }","duration":"439.759716ms","start":"2026-06-08T22:47:24.406532Z","end":"2026-06-08T22:47:24.846291Z","steps":["trace[792182930] 'process raft request' (duration: 439.49554ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:47:24.846526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:47:24.406523Z","time spent":"439.902199ms","remote":"127.0.0.1:36224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:47:24.846522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.247286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-sd8rv\" ","response":"range_response_count:1 size:22088"} {"level":"info","ts":"2026-06-08T22:47:24.846594Z","caller":"traceutil/trace.go:171","msg":"trace[2094430240] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-sd8rv; range_end:; response_count:1; response_revision:16104; }","duration":"392.373059ms","start":"2026-06-08T22:47:24.454204Z","end":"2026-06-08T22:47:24.846577Z","steps":["trace[2094430240] 'agreement among raft nodes before linearized reading' (duration: 392.159194ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:47:24.846624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.369604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-sd8rv\" ","response":"range_response_count:1 size:22088"} {"level":"warn","ts":"2026-06-08T22:47:24.846642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:47:24.454184Z","time spent":"392.449261ms","remote":"127.0.0.1:36142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22110,"request content":"key:\"/registry/pods/openstack/nova-compute-default-sd8rv\" "} {"level":"info","ts":"2026-06-08T22:47:24.846691Z","caller":"traceutil/trace.go:171","msg":"trace[1127875320] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-sd8rv; range_end:; response_count:1; response_revision:16104; }","duration":"391.463496ms","start":"2026-06-08T22:47:24.455202Z","end":"2026-06-08T22:47:24.846666Z","steps":["trace[1127875320] 'agreement among raft nodes before linearized reading' (duration: 391.22451ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:47:24.846767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:47:24.455193Z","time spent":"391.556718ms","remote":"127.0.0.1:36142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22110,"request content":"key:\"/registry/pods/openstack/nova-compute-default-sd8rv\" "} {"level":"warn","ts":"2026-06-08T22:47:24.846809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.390412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-06-08T22:47:24.846851Z","caller":"traceutil/trace.go:171","msg":"trace[1428828210] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16104; }","duration":"338.483745ms","start":"2026-06-08T22:47:24.508355Z","end":"2026-06-08T22:47:24.846838Z","steps":["trace[1428828210] 'agreement among raft nodes before linearized reading' (duration: 338.379272ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:47:24.846886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:47:24.508331Z","time spent":"338.547976ms","remote":"127.0.0.1:36198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5543,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-06-08T22:47:24.84684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.897942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-08T22:47:24.849904Z","caller":"traceutil/trace.go:171","msg":"trace[2146888510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16104; }","duration":"156.038458ms","start":"2026-06-08T22:47:24.693833Z","end":"2026-06-08T22:47:24.849871Z","steps":["trace[2146888510] 'agreement among raft nodes before linearized reading' (duration: 152.658926ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:47:24.846998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.805753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:47:24.850276Z","caller":"traceutil/trace.go:171","msg":"trace[459765255] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16104; }","duration":"342.134683ms","start":"2026-06-08T22:47:24.508128Z","end":"2026-06-08T22:47:24.850263Z","steps":["trace[459765255] 'agreement among raft nodes before linearized reading' (duration: 338.789852ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:47:24.85033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:47:24.508105Z","time spent":"342.205104ms","remote":"127.0.0.1:36198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-06-08T22:47:24.847057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.222788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-06-08T22:47:24.850533Z","caller":"traceutil/trace.go:171","msg":"trace[2139980497] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16104; }","duration":"341.715003ms","start":"2026-06-08T22:47:24.508806Z","end":"2026-06-08T22:47:24.850521Z","steps":["trace[2139980497] 'agreement among raft nodes before linearized reading' (duration: 338.184168ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:47:24.850571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:47:24.508795Z","time spent":"341.763994ms","remote":"127.0.0.1:36130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":690,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-06-08T22:47:24.847067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.946518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-08T22:47:24.850727Z","caller":"traceutil/trace.go:171","msg":"trace[826196126] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:16104; }","duration":"245.671938ms","start":"2026-06-08T22:47:24.605044Z","end":"2026-06-08T22:47:24.850716Z","steps":["trace[826196126] 'agreement among raft nodes before linearized reading' (duration: 241.768994ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:47:24.847117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.355812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:47:24.85097Z","caller":"traceutil/trace.go:171","msg":"trace[2114464888] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:16104; }","duration":"297.222916ms","start":"2026-06-08T22:47:24.553625Z","end":"2026-06-08T22:47:24.850848Z","steps":["trace[2114464888] 'agreement among raft nodes before linearized reading' (duration: 293.087385ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:47:24.847363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.760032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-npvrm\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-06-08T22:47:24.851318Z","caller":"traceutil/trace.go:171","msg":"trace[1325362498] range","detail":"{range_begin:/registry/pods/openstack/keepalived-npvrm; range_end:; response_count:1; response_revision:16104; }","duration":"264.76367ms","start":"2026-06-08T22:47:24.58654Z","end":"2026-06-08T22:47:24.851303Z","steps":["trace[1325362498] 'agreement among raft nodes before linearized reading' (duration: 260.61313ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:50:01.314924Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14662} {"level":"info","ts":"2026-06-08T22:50:01.395564Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14662,"took":"77.912966ms","hash":95466864,"current-db-size-bytes":57081856,"current-db-size":"57 MB","current-db-size-in-use-bytes":32395264,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-08T22:50:01.395638Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":95466864,"revision":14662,"compact-revision":12641} {"level":"info","ts":"2026-06-08T22:52:54.943487Z","caller":"traceutil/trace.go:171","msg":"trace[1778871675] transaction","detail":"{read_only:false; response_revision:18557; number_of_response:1; }","duration":"211.963075ms","start":"2026-06-08T22:52:54.731506Z","end":"2026-06-08T22:52:54.943469Z","steps":["trace[1778871675] 'process raft request' (duration: 211.846993ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:52:54.947573Z","caller":"traceutil/trace.go:171","msg":"trace[1745986590] transaction","detail":"{read_only:false; response_revision:18558; number_of_response:1; }","duration":"208.634215ms","start":"2026-06-08T22:52:54.73892Z","end":"2026-06-08T22:52:54.947554Z","steps":["trace[1745986590] 'process raft request' (duration: 208.42869ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:52:54.947612Z","caller":"traceutil/trace.go:171","msg":"trace[1750866858] linearizableReadLoop","detail":"{readStateIndex:19190; appliedIndex:19188; }","duration":"178.136778ms","start":"2026-06-08T22:52:54.769449Z","end":"2026-06-08T22:52:54.947586Z","steps":["trace[1750866858] 'read index received' (duration: 173.940206ms)","trace[1750866858] 'applied index is now lower than readState.Index' (duration: 4.195632ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:52:54.947855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.383884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T22:52:54.947892Z","caller":"traceutil/trace.go:171","msg":"trace[1162603319] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:18561; }","duration":"178.457265ms","start":"2026-06-08T22:52:54.769424Z","end":"2026-06-08T22:52:54.947882Z","steps":["trace[1162603319] 'agreement among raft nodes before linearized reading' (duration: 178.378843ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:53:01.127924Z","caller":"traceutil/trace.go:171","msg":"trace[2064885665] transaction","detail":"{read_only:false; response_revision:18596; number_of_response:1; }","duration":"115.80972ms","start":"2026-06-08T22:53:01.012041Z","end":"2026-06-08T22:53:01.127851Z","steps":["trace[2064885665] 'process raft request' (duration: 86.335677ms)","trace[2064885665] 'compare' (duration: 29.252088ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:53:02.366653Z","caller":"traceutil/trace.go:171","msg":"trace[1282733467] linearizableReadLoop","detail":"{readStateIndex:19231; appliedIndex:19230; }","duration":"311.40724ms","start":"2026-06-08T22:53:02.055216Z","end":"2026-06-08T22:53:02.366623Z","steps":["trace[1282733467] 'read index received' (duration: 311.099923ms)","trace[1282733467] 'applied index is now lower than readState.Index' (duration: 305.917µs)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:53:02.367381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.666366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-08T22:53:02.366775Z","caller":"traceutil/trace.go:171","msg":"trace[1093636892] transaction","detail":"{read_only:false; response_revision:18598; number_of_response:1; }","duration":"446.129258ms","start":"2026-06-08T22:53:01.920598Z","end":"2026-06-08T22:53:02.366727Z","steps":["trace[1093636892] 'process raft request' (duration: 445.846681ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:53:02.367765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.507027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-08T22:53:02.367775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:53:01.920553Z","time spent":"447.07319ms","remote":"127.0.0.1:36142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20963,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:53:02.367825Z","caller":"traceutil/trace.go:171","msg":"trace[1800439740] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18598; }","duration":"214.606749ms","start":"2026-06-08T22:53:02.153192Z","end":"2026-06-08T22:53:02.367799Z","steps":["trace[1800439740] 'agreement among raft nodes before linearized reading' (duration: 214.440815ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:53:02.367526Z","caller":"traceutil/trace.go:171","msg":"trace[1081579130] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18598; }","duration":"312.287801ms","start":"2026-06-08T22:53:02.0552Z","end":"2026-06-08T22:53:02.367488Z","steps":["trace[1081579130] 'agreement among raft nodes before linearized reading' (duration: 311.565334ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:53:02.367835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.776672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-06-08T22:53:02.367913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:53:02.055114Z","time spent":"312.758563ms","remote":"127.0.0.1:36224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-08T22:53:02.367957Z","caller":"traceutil/trace.go:171","msg":"trace[1274676839] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18598; }","duration":"295.973427ms","start":"2026-06-08T22:53:02.071949Z","end":"2026-06-08T22:53:02.367923Z","steps":["trace[1274676839] 'agreement among raft nodes before linearized reading' (duration: 295.605217ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:53:02.731037Z","caller":"traceutil/trace.go:171","msg":"trace[1115872880] transaction","detail":"{read_only:false; response_revision:18600; number_of_response:1; }","duration":"194.605695ms","start":"2026-06-08T22:53:02.536392Z","end":"2026-06-08T22:53:02.730998Z","steps":["trace[1115872880] 'process raft request' (duration: 194.506893ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:53:02.731231Z","caller":"traceutil/trace.go:171","msg":"trace[1483048705] linearizableReadLoop","detail":"{readStateIndex:19232; appliedIndex:19231; }","duration":"354.46395ms","start":"2026-06-08T22:53:02.376712Z","end":"2026-06-08T22:53:02.731176Z","steps":["trace[1483048705] 'read index received' (duration: 308.348186ms)","trace[1483048705] 'applied index is now lower than readState.Index' (duration: 46.106644ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:53:02.731231Z","caller":"traceutil/trace.go:171","msg":"trace[946831228] transaction","detail":"{read_only:false; response_revision:18599; number_of_response:1; }","duration":"354.637345ms","start":"2026-06-08T22:53:02.376537Z","end":"2026-06-08T22:53:02.731175Z","steps":["trace[946831228] 'process raft request' (duration: 308.532901ms)","trace[946831228] 'compare' (duration: 45.614743ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:53:02.7317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.956292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18823"} {"level":"info","ts":"2026-06-08T22:53:02.731745Z","caller":"traceutil/trace.go:171","msg":"trace[921478491] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:18600; }","duration":"355.039005ms","start":"2026-06-08T22:53:02.376693Z","end":"2026-06-08T22:53:02.731732Z","steps":["trace[921478491] 'agreement among raft nodes before linearized reading' (duration: 354.795959ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:53:02.731785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:53:02.376684Z","time spent":"355.093146ms","remote":"127.0.0.1:36140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18845,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-06-08T22:53:02.731834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.653588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-06-08T22:53:02.731826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:53:02.376383Z","time spent":"355.28492ms","remote":"127.0.0.1:36224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:53:02.731924Z","caller":"traceutil/trace.go:171","msg":"trace[1000801422] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18600; }","duration":"307.796913ms","start":"2026-06-08T22:53:02.424099Z","end":"2026-06-08T22:53:02.731896Z","steps":["trace[1000801422] 'agreement among raft nodes before linearized reading' (duration: 307.527386ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:53:02.731984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:53:02.424074Z","time spent":"307.900994ms","remote":"127.0.0.1:36224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-08T22:55:01.325542Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17240} {"level":"info","ts":"2026-06-08T22:55:01.422609Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17240,"took":"93.765907ms","hash":2526110776,"current-db-size-bytes":57081856,"current-db-size":"57 MB","current-db-size-in-use-bytes":30306304,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-08T22:55:01.422688Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2526110776,"revision":17240,"compact-revision":14662} {"level":"info","ts":"2026-06-08T22:55:03.421566Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"50e03cebfd94cbba","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-08T22:55:03.427222Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-08T22:55:03.427312Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-08T23:00:01.332112Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19324} {"level":"info","ts":"2026-06-08T23:00:01.431253Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19324,"took":"96.129546ms","hash":2493314699,"current-db-size-bytes":57081856,"current-db-size":"57 MB","current-db-size-in-use-bytes":24719360,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-06-08T23:00:01.431319Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2493314699,"revision":19324,"compact-revision":17240}