{"level":"warn","ts":"2026-08-06T01:01:54.484194Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-06T01:01:54.484293Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.48:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.48:2380","--initial-cluster=instance=https://199.19.213.48:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.48:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.48: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-08-06T01:01:54.484361Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-06T01:01:54.484374Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.48:2380"]} {"level":"info","ts":"2026-08-06T01:01:54.484395Z","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-08-06T01:01:54.485072Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.48:2379"]} {"level":"info","ts":"2026-08-06T01:01:54.48515Z","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.19.213.48:2380"],"listen-peer-urls":["https://199.19.213.48:2380"],"advertise-client-urls":["https://199.19.213.48:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.48:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.48: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-08-06T01:01:54.488708Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.316761ms"} {"level":"info","ts":"2026-08-06T01:01:54.494328Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7317567213c7319d","cluster-id":"d22ed1e1757a8527"} {"level":"info","ts":"2026-08-06T01:01:54.49441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d switched to configuration voters=()"} {"level":"info","ts":"2026-08-06T01:01:54.494429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d became follower at term 0"} {"level":"info","ts":"2026-08-06T01:01:54.494449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7317567213c7319d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-06T01:01:54.494457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d became follower at term 1"} {"level":"info","ts":"2026-08-06T01:01:54.494492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d switched to configuration voters=(8293192286784139677)"} {"level":"warn","ts":"2026-08-06T01:01:54.501401Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-06T01:01:54.503028Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-06T01:01:54.504241Z","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-08-06T01:01:54.50729Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7317567213c7319d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-06T01:01:54.510131Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7317567213c7319d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-06T01:01:54.508705Z","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-08-06T01:01:54.510333Z","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-08-06T01:01:54.510371Z","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-08-06T01:01:54.510648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d switched to configuration voters=(8293192286784139677)"} {"level":"info","ts":"2026-08-06T01:01:54.51122Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d22ed1e1757a8527","local-member-id":"7317567213c7319d","added-peer-id":"7317567213c7319d","added-peer-peer-urls":["https://199.19.213.48:2380"]} {"level":"info","ts":"2026-08-06T01:01:54.512203Z","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-08-06T01:01:54.512453Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7317567213c7319d","initial-advertise-peer-urls":["https://199.19.213.48:2380"],"listen-peer-urls":["https://199.19.213.48:2380"],"advertise-client-urls":["https://199.19.213.48:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.48:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-06T01:01:54.512501Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-06T01:01:54.512559Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.48:2380"} {"level":"info","ts":"2026-08-06T01:01:54.512586Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.48:2380"} {"level":"info","ts":"2026-08-06T01:01:55.095144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d is starting a new election at term 1"} {"level":"info","ts":"2026-08-06T01:01:55.095217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d became pre-candidate at term 1"} {"level":"info","ts":"2026-08-06T01:01:55.095255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d received MsgPreVoteResp from 7317567213c7319d at term 1"} {"level":"info","ts":"2026-08-06T01:01:55.09527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d became candidate at term 2"} {"level":"info","ts":"2026-08-06T01:01:55.095278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d received MsgVoteResp from 7317567213c7319d at term 2"} {"level":"info","ts":"2026-08-06T01:01:55.095294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d became leader at term 2"} {"level":"info","ts":"2026-08-06T01:01:55.095305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7317567213c7319d elected leader 7317567213c7319d at term 2"} {"level":"info","ts":"2026-08-06T01:01:55.096383Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T01:01:55.097352Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-06T01:01:55.097361Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7317567213c7319d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.48:2379]}","request-path":"/0/members/7317567213c7319d/attributes","cluster-id":"d22ed1e1757a8527","publish-timeout":"7s"} {"level":"info","ts":"2026-08-06T01:01:55.097823Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-06T01:01:55.097956Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-06T01:01:55.097988Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-06T01:01:55.097832Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d22ed1e1757a8527","local-member-id":"7317567213c7319d","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T01:01:55.098213Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T01:01:55.098255Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T01:01:55.100221Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.48:2379"} {"level":"info","ts":"2026-08-06T01:01:55.101253Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-06T01:02:11.96572Z","caller":"traceutil/trace.go:171","msg":"trace[1230562885] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"249.63003ms","start":"2026-08-06T01:02:11.716033Z","end":"2026-08-06T01:02:11.965663Z","steps":["trace[1230562885] 'process raft request' (duration: 249.363803ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:40.740497Z","caller":"traceutil/trace.go:171","msg":"trace[383853324] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"290.737705ms","start":"2026-08-06T01:02:40.449718Z","end":"2026-08-06T01:02:40.740456Z","steps":["trace[383853324] 'process raft request' (duration: 230.586905ms)","trace[383853324] 'compare' (duration: 59.966315ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:02:40.744939Z","caller":"traceutil/trace.go:171","msg":"trace[304233149] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"294.911532ms","start":"2026-08-06T01:02:40.450014Z","end":"2026-08-06T01:02:40.744926Z","steps":["trace[304233149] 'process raft request' (duration: 294.800219ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:40.745056Z","caller":"traceutil/trace.go:171","msg":"trace[124737042] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"294.014387ms","start":"2026-08-06T01:02:40.450991Z","end":"2026-08-06T01:02:40.745006Z","steps":["trace[124737042] 'process raft request' (duration: 293.848053ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:40.745076Z","caller":"traceutil/trace.go:171","msg":"trace[458872363] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"292.260308ms","start":"2026-08-06T01:02:40.452774Z","end":"2026-08-06T01:02:40.745034Z","steps":["trace[458872363] 'process raft request' (duration: 292.090153ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:40.745213Z","caller":"traceutil/trace.go:171","msg":"trace[736594262] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"285.885939ms","start":"2026-08-06T01:02:40.459315Z","end":"2026-08-06T01:02:40.745201Z","steps":["trace[736594262] 'process raft request' (duration: 285.698055ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:40.745231Z","caller":"traceutil/trace.go:171","msg":"trace[2068639964] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"292.277068ms","start":"2026-08-06T01:02:40.452915Z","end":"2026-08-06T01:02:40.745192Z","steps":["trace[2068639964] 'process raft request' (duration: 292.018951ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:40.744887Z","caller":"traceutil/trace.go:171","msg":"trace[531758319] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"294.892691ms","start":"2026-08-06T01:02:40.449968Z","end":"2026-08-06T01:02:40.744861Z","steps":["trace[531758319] 'process raft request' (duration: 294.749587ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:40.745401Z","caller":"traceutil/trace.go:171","msg":"trace[675520760] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"284.354927ms","start":"2026-08-06T01:02:40.461Z","end":"2026-08-06T01:02:40.745355Z","steps":["trace[675520760] 'process raft request' (duration: 284.099089ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:40.745509Z","caller":"traceutil/trace.go:171","msg":"trace[899332352] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"283.666058ms","start":"2026-08-06T01:02:40.461832Z","end":"2026-08-06T01:02:40.745498Z","steps":["trace[899332352] 'process raft request' (duration: 283.472762ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:40.745559Z","caller":"traceutil/trace.go:171","msg":"trace[1501973975] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"283.144463ms","start":"2026-08-06T01:02:40.462403Z","end":"2026-08-06T01:02:40.745548Z","steps":["trace[1501973975] 'process raft request' (duration: 283.013559ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:40.745748Z","caller":"traceutil/trace.go:171","msg":"trace[1429022398] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"276.193499ms","start":"2026-08-06T01:02:40.469529Z","end":"2026-08-06T01:02:40.745723Z","steps":["trace[1429022398] 'process raft request' (duration: 275.971333ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:40.745305Z","caller":"traceutil/trace.go:171","msg":"trace[936365558] transaction","detail":"{read_only:false; number_of_response:1; response_revision:557; }","duration":"292.151875ms","start":"2026-08-06T01:02:40.453031Z","end":"2026-08-06T01:02:40.745183Z","steps":["trace[936365558] 'process raft request' (duration: 291.948259ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:40.745938Z","caller":"traceutil/trace.go:171","msg":"trace[435729957] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"169.224439ms","start":"2026-08-06T01:02:40.576688Z","end":"2026-08-06T01:02:40.745912Z","steps":["trace[435729957] 'process raft request' (duration: 169.107815ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:46.987003Z","caller":"traceutil/trace.go:171","msg":"trace[390578914] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"170.543035ms","start":"2026-08-06T01:02:46.816429Z","end":"2026-08-06T01:02:46.986972Z","steps":["trace[390578914] 'process raft request' (duration: 159.96712ms)","trace[390578914] 'compare' (duration: 10.339289ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:02:47.346694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.170432ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-06T01:02:47.346852Z","caller":"traceutil/trace.go:171","msg":"trace[811096147] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"267.510936ms","start":"2026-08-06T01:02:47.079322Z","end":"2026-08-06T01:02:47.346833Z","steps":["trace[811096147] 'process raft request' (duration: 138.737707ms)","trace[811096147] 'compare' (duration: 127.818763ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:02:48.031492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.056955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2gs97\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-06T01:02:48.031641Z","caller":"traceutil/trace.go:171","msg":"trace[886290092] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2gs97; range_end:; response_count:1; response_revision:651; }","duration":"519.275232ms","start":"2026-08-06T01:02:47.512335Z","end":"2026-08-06T01:02:48.031611Z","steps":["trace[886290092] 'range keys from in-memory index tree' (duration: 518.81924ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:02:48.031686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:47.512314Z","time spent":"519.363744ms","remote":"127.0.0.1:37128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-2gs97\" "} {"level":"info","ts":"2026-08-06T01:02:48.031432Z","caller":"traceutil/trace.go:171","msg":"trace[1915545126] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"467.472514ms","start":"2026-08-06T01:02:47.563915Z","end":"2026-08-06T01:02:48.031388Z","steps":["trace[1915545126] 'process raft request' (duration: 415.063859ms)","trace[1915545126] 'compare' (duration: 52.006153ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:02:48.032464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:02:47.563886Z","time spent":"468.162923ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:02:48.03721Z","caller":"traceutil/trace.go:171","msg":"trace[1174202991] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"282.566996ms","start":"2026-08-06T01:02:47.754575Z","end":"2026-08-06T01:02:48.037142Z","steps":["trace[1174202991] 'process raft request' (duration: 281.581688ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:03:03.242199Z","caller":"traceutil/trace.go:171","msg":"trace[860578011] linearizableReadLoop","detail":"{readStateIndex:848; appliedIndex:847; }","duration":"118.672566ms","start":"2026-08-06T01:03:03.123499Z","end":"2026-08-06T01:03:03.242172Z","steps":["trace[860578011] 'read index received' (duration: 118.4625ms)","trace[860578011] 'applied index is now lower than readState.Index' (duration: 208.826µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:03:03.242359Z","caller":"traceutil/trace.go:171","msg":"trace[1110667968] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"164.549498ms","start":"2026-08-06T01:03:03.077796Z","end":"2026-08-06T01:03:03.242346Z","steps":["trace[1110667968] 'process raft request' (duration: 164.159888ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:03:03.242994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.452428ms","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-08-06T01:03:03.243128Z","caller":"traceutil/trace.go:171","msg":"trace[1523596746] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:817; }","duration":"119.651933ms","start":"2026-08-06T01:03:03.123447Z","end":"2026-08-06T01:03:03.243099Z","steps":["trace[1523596746] 'agreement among raft nodes before linearized reading' (duration: 119.249653ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:03:03.519174Z","caller":"traceutil/trace.go:171","msg":"trace[2014208406] linearizableReadLoop","detail":"{readStateIndex:849; appliedIndex:848; }","duration":"139.266182ms","start":"2026-08-06T01:03:03.379876Z","end":"2026-08-06T01:03:03.519142Z","steps":["trace[2014208406] 'read index received' (duration: 49.130553ms)","trace[2014208406] 'applied index is now lower than readState.Index' (duration: 90.133928ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:03:03.519321Z","caller":"traceutil/trace.go:171","msg":"trace[21458884] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"271.841717ms","start":"2026-08-06T01:03:03.247435Z","end":"2026-08-06T01:03:03.519277Z","steps":["trace[21458884] 'process raft request' (duration: 181.598755ms)","trace[21458884] 'compare' (duration: 89.948893ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:03:03.519612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.706094ms","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-08-06T01:03:03.519775Z","caller":"traceutil/trace.go:171","msg":"trace[1015668039] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:818; }","duration":"139.859799ms","start":"2026-08-06T01:03:03.37986Z","end":"2026-08-06T01:03:03.519719Z","steps":["trace[1015668039] 'agreement among raft nodes before linearized reading' (duration: 139.385125ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:03:46.00241Z","caller":"traceutil/trace.go:171","msg":"trace[473125817] linearizableReadLoop","detail":"{readStateIndex:1386; appliedIndex:1385; }","duration":"118.830644ms","start":"2026-08-06T01:03:45.883542Z","end":"2026-08-06T01:03:46.002373Z","steps":["trace[473125817] 'read index received' (duration: 118.541486ms)","trace[473125817] 'applied index is now lower than readState.Index' (duration: 287.597µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:03:46.002569Z","caller":"traceutil/trace.go:171","msg":"trace[1221479747] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"120.688996ms","start":"2026-08-06T01:03:45.881836Z","end":"2026-08-06T01:03:46.002525Z","steps":["trace[1221479747] 'process raft request' (duration: 120.288125ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:03:46.002798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.221985ms","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-08-06T01:03:46.00313Z","caller":"traceutil/trace.go:171","msg":"trace[394431522] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1342; }","duration":"119.612186ms","start":"2026-08-06T01:03:45.883497Z","end":"2026-08-06T01:03:46.003109Z","steps":["trace[394431522] 'agreement among raft nodes before linearized reading' (duration: 119.043671ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:03:53.220094Z","caller":"traceutil/trace.go:171","msg":"trace[820046868] transaction","detail":"{read_only:false; response_revision:1466; number_of_response:1; }","duration":"243.174272ms","start":"2026-08-06T01:03:52.976882Z","end":"2026-08-06T01:03:53.220056Z","steps":["trace[820046868] 'process raft request' (duration: 242.909574ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:04:17.125743Z","caller":"traceutil/trace.go:171","msg":"trace[104972199] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"100.904032ms","start":"2026-08-06T01:04:17.024808Z","end":"2026-08-06T01:04:17.125712Z","steps":["trace[104972199] 'process raft request' (duration: 68.823675ms)","trace[104972199] 'compare' (duration: 31.959534ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:04:17.142032Z","caller":"traceutil/trace.go:171","msg":"trace[1902915304] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"104.675898ms","start":"2026-08-06T01:04:17.037341Z","end":"2026-08-06T01:04:17.142017Z","steps":["trace[1902915304] 'process raft request' (duration: 104.605706ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:04:17.859581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.137151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-06T01:04:17.859672Z","caller":"traceutil/trace.go:171","msg":"trace[206439189] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1866; }","duration":"391.282326ms","start":"2026-08-06T01:04:17.468367Z","end":"2026-08-06T01:04:17.859649Z","steps":["trace[206439189] 'range keys from in-memory index tree' (duration: 390.924325ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:04:17.859654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.118909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ddac9a81-ef54-40eb-8f9d-076254eb1a21\" ","response":"range_response_count:1 size:3746"} {"level":"warn","ts":"2026-08-06T01:04:17.859743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:04:17.468342Z","time spent":"391.389929ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-06T01:04:17.85978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.146767ms","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-08-06T01:04:17.85986Z","caller":"traceutil/trace.go:171","msg":"trace[1153582270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1866; }","duration":"438.27679ms","start":"2026-08-06T01:04:17.421565Z","end":"2026-08-06T01:04:17.859842Z","steps":["trace[1153582270] 'range keys from in-memory index tree' (duration: 437.91437ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:04:17.859896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:04:17.42154Z","time spent":"438.348852ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-06T01:04:17.860085Z","caller":"traceutil/trace.go:171","msg":"trace[1956262847] linearizableReadLoop","detail":"{readStateIndex:1939; appliedIndex:1938; }","duration":"151.389545ms","start":"2026-08-06T01:04:17.708682Z","end":"2026-08-06T01:04:17.860071Z","steps":["trace[1956262847] 'read index received' (duration: 52.529069ms)","trace[1956262847] 'applied index is now lower than readState.Index' (duration: 98.859496ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:04:17.860208Z","caller":"traceutil/trace.go:171","msg":"trace[873411054] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"211.985701ms","start":"2026-08-06T01:04:17.648211Z","end":"2026-08-06T01:04:17.860196Z","steps":["trace[873411054] 'process raft request' (duration: 112.989021ms)","trace[873411054] 'compare' (duration: 98.110935ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:04:17.860239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.599831ms","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-08-06T01:04:17.860271Z","caller":"traceutil/trace.go:171","msg":"trace[1327648913] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1867; }","duration":"151.679113ms","start":"2026-08-06T01:04:17.708583Z","end":"2026-08-06T01:04:17.860262Z","steps":["trace[1327648913] 'agreement among raft nodes before linearized reading' (duration: 151.544189ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:04:17.859802Z","caller":"traceutil/trace.go:171","msg":"trace[1538175124] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ddac9a81-ef54-40eb-8f9d-076254eb1a21; range_end:; response_count:1; response_revision:1866; }","duration":"234.709147ms","start":"2026-08-06T01:04:17.625061Z","end":"2026-08-06T01:04:17.85977Z","steps":["trace[1538175124] 'range keys from in-memory index tree' (duration: 233.902003ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:04:18.113484Z","caller":"traceutil/trace.go:171","msg":"trace[36065879] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"248.921563ms","start":"2026-08-06T01:04:17.86453Z","end":"2026-08-06T01:04:18.113452Z","steps":["trace[36065879] 'process raft request' (duration: 231.578318ms)","trace[36065879] 'compare' (duration: 16.41243ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:04:18.113499Z","caller":"traceutil/trace.go:171","msg":"trace[404089140] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"248.932084ms","start":"2026-08-06T01:04:17.864531Z","end":"2026-08-06T01:04:18.113463Z","steps":["trace[404089140] 'process raft request' (duration: 248.692987ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:04:18.113599Z","caller":"traceutil/trace.go:171","msg":"trace[1185319737] linearizableReadLoop","detail":"{readStateIndex:1942; appliedIndex:1940; }","duration":"165.51592ms","start":"2026-08-06T01:04:17.948065Z","end":"2026-08-06T01:04:18.113581Z","steps":["trace[1185319737] 'read index received' (duration: 147.881056ms)","trace[1185319737] 'applied index is now lower than readState.Index' (duration: 17.632794ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:04:18.113776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.697724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:04:18.113817Z","caller":"traceutil/trace.go:171","msg":"trace[2006299197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1870; }","duration":"165.787758ms","start":"2026-08-06T01:04:17.948018Z","end":"2026-08-06T01:04:18.113806Z","steps":["trace[2006299197] 'agreement among raft nodes before linearized reading' (duration: 165.712626ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:04:18.113841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.861989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-06T01:04:18.114204Z","caller":"traceutil/trace.go:171","msg":"trace[82785646] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1870; }","duration":"112.28166ms","start":"2026-08-06T01:04:18.00191Z","end":"2026-08-06T01:04:18.114192Z","steps":["trace[82785646] 'agreement among raft nodes before linearized reading' (duration: 111.829678ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:04:18.686994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.104092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.48\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-06T01:04:18.687091Z","caller":"traceutil/trace.go:171","msg":"trace[656635296] range","detail":"{range_begin:/registry/masterleases/199.19.213.48; range_end:; response_count:1; response_revision:1873; }","duration":"302.243405ms","start":"2026-08-06T01:04:18.384822Z","end":"2026-08-06T01:04:18.687066Z","steps":["trace[656635296] 'range keys from in-memory index tree' (duration: 301.7056ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:04:18.687141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:04:18.384807Z","time spent":"302.323877ms","remote":"127.0.0.1:36972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.48\" "} {"level":"info","ts":"2026-08-06T01:04:33.460131Z","caller":"traceutil/trace.go:171","msg":"trace[414285382] linearizableReadLoop","detail":"{readStateIndex:2077; appliedIndex:2076; }","duration":"233.660717ms","start":"2026-08-06T01:04:33.22639Z","end":"2026-08-06T01:04:33.460051Z","steps":["trace[414285382] 'read index received' (duration: 208.535624ms)","trace[414285382] 'applied index is now lower than readState.Index' (duration: 25.123233ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:04:33.460162Z","caller":"traceutil/trace.go:171","msg":"trace[1197983381] transaction","detail":"{read_only:false; response_revision:2002; number_of_response:1; }","duration":"266.892656ms","start":"2026-08-06T01:04:33.193222Z","end":"2026-08-06T01:04:33.460115Z","steps":["trace[1197983381] 'process raft request' (duration: 241.650629ms)","trace[1197983381] 'compare' (duration: 24.715052ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:04:33.460422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.000516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-06T01:04:33.460526Z","caller":"traceutil/trace.go:171","msg":"trace[792483165] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2002; }","duration":"234.19011ms","start":"2026-08-06T01:04:33.226311Z","end":"2026-08-06T01:04:33.460501Z","steps":["trace[792483165] 'agreement among raft nodes before linearized reading' (duration: 233.917753ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:04:33.462758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.04916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:04:33.462823Z","caller":"traceutil/trace.go:171","msg":"trace[1002174120] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:2003; }","duration":"144.196313ms","start":"2026-08-06T01:04:33.318613Z","end":"2026-08-06T01:04:33.462809Z","steps":["trace[1002174120] 'agreement among raft nodes before linearized reading' (duration: 144.003258ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:05:02.226724Z","caller":"traceutil/trace.go:171","msg":"trace[89703884] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"194.057411ms","start":"2026-08-06T01:05:02.03262Z","end":"2026-08-06T01:05:02.226678Z","steps":["trace[89703884] 'process raft request' (duration: 193.816675ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:05:02.419152Z","caller":"traceutil/trace.go:171","msg":"trace[641545256] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"124.037707ms","start":"2026-08-06T01:05:02.295084Z","end":"2026-08-06T01:05:02.419121Z","steps":["trace[641545256] 'process raft request' (duration: 123.895704ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:05:02.419148Z","caller":"traceutil/trace.go:171","msg":"trace[1094818358] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"186.434348ms","start":"2026-08-06T01:05:02.232656Z","end":"2026-08-06T01:05:02.41909Z","steps":["trace[1094818358] 'process raft request' (duration: 101.015293ms)","trace[1094818358] 'compare' (duration: 85.064565ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:05:02.563555Z","caller":"traceutil/trace.go:171","msg":"trace[1812577439] linearizableReadLoop","detail":"{readStateIndex:2249; appliedIndex:2248; }","duration":"119.147291ms","start":"2026-08-06T01:05:02.444376Z","end":"2026-08-06T01:05:02.563523Z","steps":["trace[1812577439] 'read index received' (duration: 78.946543ms)","trace[1812577439] 'applied index is now lower than readState.Index' (duration: 40.199758ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:05:02.56364Z","caller":"traceutil/trace.go:171","msg":"trace[496591149] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"139.44254ms","start":"2026-08-06T01:05:02.424166Z","end":"2026-08-06T01:05:02.563608Z","steps":["trace[496591149] 'process raft request' (duration: 99.186291ms)","trace[496591149] 'compare' (duration: 40.023033ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:05:02.563805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.431688ms","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-08-06T01:05:02.564353Z","caller":"traceutil/trace.go:171","msg":"trace[1714407308] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2168; }","duration":"119.590094ms","start":"2026-08-06T01:05:02.444313Z","end":"2026-08-06T01:05:02.563903Z","steps":["trace[1714407308] 'agreement among raft nodes before linearized reading' (duration: 119.368338ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:05:02.719545Z","caller":"traceutil/trace.go:171","msg":"trace[76576242] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"150.563812ms","start":"2026-08-06T01:05:02.568948Z","end":"2026-08-06T01:05:02.719512Z","steps":["trace[76576242] 'process raft request' (duration: 124.246733ms)","trace[76576242] 'compare' (duration: 26.064941ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:05:08.358779Z","caller":"traceutil/trace.go:171","msg":"trace[1167607349] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"109.625494ms","start":"2026-08-06T01:05:08.249114Z","end":"2026-08-06T01:05:08.358739Z","steps":["trace[1167607349] 'process raft request' (duration: 109.404407ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:05:08.631405Z","caller":"traceutil/trace.go:171","msg":"trace[1755613420] linearizableReadLoop","detail":"{readStateIndex:2279; appliedIndex:2278; }","duration":"243.801366ms","start":"2026-08-06T01:05:08.387556Z","end":"2026-08-06T01:05:08.631358Z","steps":["trace[1755613420] 'read index received' (duration: 189.966137ms)","trace[1755613420] 'applied index is now lower than readState.Index' (duration: 53.833369ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:05:08.631469Z","caller":"traceutil/trace.go:171","msg":"trace[1707608286] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"264.204038ms","start":"2026-08-06T01:05:08.367208Z","end":"2026-08-06T01:05:08.631412Z","steps":["trace[1707608286] 'process raft request' (duration: 210.184404ms)","trace[1707608286] 'compare' (duration: 53.687515ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:05:08.631821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.207789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.48\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-06T01:05:08.631962Z","caller":"traceutil/trace.go:171","msg":"trace[1910391706] range","detail":"{range_begin:/registry/masterleases/199.19.213.48; range_end:; response_count:1; response_revision:2196; }","duration":"244.446005ms","start":"2026-08-06T01:05:08.387482Z","end":"2026-08-06T01:05:08.631928Z","steps":["trace[1910391706] 'agreement among raft nodes before linearized reading' (duration: 243.984392ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:05:08.688382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.917273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-06T01:05:08.688475Z","caller":"traceutil/trace.go:171","msg":"trace[1354465606] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2197; }","duration":"242.093148ms","start":"2026-08-06T01:05:08.446357Z","end":"2026-08-06T01:05:08.68845Z","steps":["trace[1354465606] 'agreement among raft nodes before linearized reading' (duration: 241.81052ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:05:08.688847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.079975ms","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-08-06T01:05:08.688945Z","caller":"traceutil/trace.go:171","msg":"trace[97266566] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2197; }","duration":"223.237689ms","start":"2026-08-06T01:05:08.465685Z","end":"2026-08-06T01:05:08.688923Z","steps":["trace[97266566] 'agreement among raft nodes before linearized reading' (duration: 222.785487ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:05:08.68912Z","caller":"traceutil/trace.go:171","msg":"trace[1608872591] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"243.288821ms","start":"2026-08-06T01:05:08.445705Z","end":"2026-08-06T01:05:08.688994Z","steps":["trace[1608872591] 'process raft request' (duration: 242.298764ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:05:57.599115Z","caller":"traceutil/trace.go:171","msg":"trace[980032784] linearizableReadLoop","detail":"{readStateIndex:2739; appliedIndex:2738; }","duration":"196.35122ms","start":"2026-08-06T01:05:57.402737Z","end":"2026-08-06T01:05:57.599089Z","steps":["trace[980032784] 'read index received' (duration: 196.183896ms)","trace[980032784] 'applied index is now lower than readState.Index' (duration: 166.084µs)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:05:57.599362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.597308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T01:05:57.599494Z","caller":"traceutil/trace.go:171","msg":"trace[692736896] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:2640; }","duration":"196.72526ms","start":"2026-08-06T01:05:57.402684Z","end":"2026-08-06T01:05:57.599409Z","steps":["trace[692736896] 'agreement among raft nodes before linearized reading' (duration: 196.530386ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:05:57.599507Z","caller":"traceutil/trace.go:171","msg":"trace[490514912] transaction","detail":"{read_only:false; response_revision:2640; number_of_response:1; }","duration":"365.444879ms","start":"2026-08-06T01:05:57.233923Z","end":"2026-08-06T01:05:57.599368Z","steps":["trace[490514912] 'process raft request' (duration: 364.955935ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:05:57.59981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:05:57.233877Z","time spent":"365.82803ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:06:02.691452Z","caller":"traceutil/trace.go:171","msg":"trace[1648291104] transaction","detail":"{read_only:false; response_revision:2672; number_of_response:1; }","duration":"115.86285ms","start":"2026-08-06T01:06:02.575545Z","end":"2026-08-06T01:06:02.691408Z","steps":["trace[1648291104] 'process raft request' (duration: 115.554151ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:21.588916Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3575189414622647613,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T01:06:22.089064Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3575189414622647613,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T01:06:22.589475Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3575189414622647613,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T01:06:22.946776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000319657s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-06T01:06:22.946933Z","caller":"traceutil/trace.go:171","msg":"trace[383674718] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000551353s","start":"2026-08-06T01:06:20.946346Z","end":"2026-08-06T01:06:22.946898Z","steps":["trace[383674718] 'agreement among raft nodes before linearized reading' (duration: 2.000364389s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:22.947005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:20.946318Z","time spent":"2.000674206s","remote":"127.0.0.1:36940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/06 01:06:22 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-06T01:06:23.090529Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3575189414622647613,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T01:06:23.59081Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3575189414622647613,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T01:06:24.947117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999975538s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-06T01:06:24.947232Z","caller":"traceutil/trace.go:171","msg":"trace[2072353568] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000146203s","start":"2026-08-06T01:06:22.947059Z","end":"2026-08-06T01:06:24.947205Z","steps":["trace[2072353568] 'agreement among raft nodes before linearized reading' (duration: 2.000025539s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:24.947275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:22.947027Z","time spent":"2.000240526s","remote":"127.0.0.1:36948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/06 01:06:24 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-06T01:06:24.947607Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3575189414622647613,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T01:06:25.399923Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.638346327s","expected-duration":"1s"} {"level":"info","ts":"2026-08-06T01:06:25.4005Z","caller":"traceutil/trace.go:171","msg":"trace[1564437092] transaction","detail":"{read_only:false; response_revision:2782; number_of_response:1; }","duration":"4.639047616s","start":"2026-08-06T01:06:20.761424Z","end":"2026-08-06T01:06:25.400472Z","steps":["trace[1564437092] 'process raft request' (duration: 4.638879901s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:25.400644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:20.761393Z","time spent":"4.63917229s","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:06:25.404095Z","caller":"traceutil/trace.go:171","msg":"trace[1328052965] transaction","detail":"{read_only:false; response_revision:2783; number_of_response:1; }","duration":"4.450218415s","start":"2026-08-06T01:06:20.953829Z","end":"2026-08-06T01:06:25.404048Z","steps":["trace[1328052965] 'process raft request' (duration: 4.449728152s)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:06:25.404227Z","caller":"traceutil/trace.go:171","msg":"trace[292861093] transaction","detail":"{read_only:false; response_revision:2784; number_of_response:1; }","duration":"3.384017121s","start":"2026-08-06T01:06:22.020176Z","end":"2026-08-06T01:06:25.404193Z","steps":["trace[292861093] 'process raft request' (duration: 3.383611581s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:25.404319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:20.953812Z","time spent":"4.450385311s","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:06:25.40439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:22.020165Z","time spent":"3.384135764s","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:06:25.404576Z","caller":"traceutil/trace.go:171","msg":"trace[608885248] transaction","detail":"{read_only:false; response_revision:2785; number_of_response:1; }","duration":"2.526562532s","start":"2026-08-06T01:06:22.878007Z","end":"2026-08-06T01:06:25.404569Z","steps":["trace[608885248] 'process raft request' (duration: 2.525828491s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:25.404615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:22.877974Z","time spent":"2.526619845s","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:06:25.411184Z","caller":"traceutil/trace.go:171","msg":"trace[1283680539] linearizableReadLoop","detail":"{readStateIndex:2891; appliedIndex:2886; }","duration":"4.464733381s","start":"2026-08-06T01:06:20.94642Z","end":"2026-08-06T01:06:25.411154Z","steps":["trace[1283680539] 'read index received' (duration: 4.453848537s)","trace[1283680539] 'applied index is now lower than readState.Index' (duration: 10.883544ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:06:25.411641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.242195048s","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-08-06T01:06:25.41167Z","caller":"traceutil/trace.go:171","msg":"trace[297176574] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2785; }","duration":"4.242286381s","start":"2026-08-06T01:06:21.169376Z","end":"2026-08-06T01:06:25.411662Z","steps":["trace[297176574] 'agreement among raft nodes before linearized reading' (duration: 4.242158858s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:25.411697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:21.16935Z","time spent":"4.242339472s","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-06T01:06:25.412342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.662214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12209"} {"level":"info","ts":"2026-08-06T01:06:25.412366Z","caller":"traceutil/trace.go:171","msg":"trace[929824415] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2785; }","duration":"405.737435ms","start":"2026-08-06T01:06:25.006622Z","end":"2026-08-06T01:06:25.412359Z","steps":["trace[929824415] 'agreement among raft nodes before linearized reading' (duration: 405.666974ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:25.412383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:25.006598Z","time spent":"405.780446ms","remote":"127.0.0.1:37128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12232,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-06T01:06:25.412505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.362359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:06:25.412525Z","caller":"traceutil/trace.go:171","msg":"trace[1100559823] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2785; }","duration":"458.383079ms","start":"2026-08-06T01:06:24.954136Z","end":"2026-08-06T01:06:25.412519Z","steps":["trace[1100559823] 'agreement among raft nodes before linearized reading' (duration: 458.348978ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:25.412539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:24.954105Z","time spent":"458.43126ms","remote":"127.0.0.1:36940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-06T01:06:25.412664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.406591872s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/valkey-node-0\" ","response":"range_response_count:1 size:17080"} {"level":"warn","ts":"2026-08-06T01:06:25.412683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.229074651s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-06T01:06:25.412699Z","caller":"traceutil/trace.go:171","msg":"trace[2022984211] range","detail":"{range_begin:/registry/pods/openstack/valkey-node-0; range_end:; response_count:1; response_revision:2785; }","duration":"3.406674744s","start":"2026-08-06T01:06:22.006015Z","end":"2026-08-06T01:06:25.41269Z","steps":["trace[2022984211] 'agreement among raft nodes before linearized reading' (duration: 3.406571331s)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:06:25.412707Z","caller":"traceutil/trace.go:171","msg":"trace[1662242293] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2785; }","duration":"4.229113503s","start":"2026-08-06T01:06:21.183585Z","end":"2026-08-06T01:06:25.412699Z","steps":["trace[1662242293] 'agreement among raft nodes before linearized reading' (duration: 4.229044382s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:25.412721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:22.005994Z","time spent":"3.406722185s","remote":"127.0.0.1:37128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":17103,"request content":"key:\"/registry/pods/openstack/valkey-node-0\" "} {"level":"warn","ts":"2026-08-06T01:06:25.412726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:21.18358Z","time spent":"4.229141514s","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-06T01:06:25.412722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.046944191s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T01:06:25.412787Z","caller":"traceutil/trace.go:171","msg":"trace[1047834341] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:2785; }","duration":"2.047084004s","start":"2026-08-06T01:06:23.365689Z","end":"2026-08-06T01:06:25.412773Z","steps":["trace[1047834341] 'agreement among raft nodes before linearized reading' (duration: 2.04692422s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:25.412833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:23.365663Z","time spent":"2.047161197s","remote":"127.0.0.1:37124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-08-06T01:06:25.412848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.230493882s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-06T01:06:25.412861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.230242647s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:06:25.412869Z","caller":"traceutil/trace.go:171","msg":"trace[536587180] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2785; }","duration":"4.230535033s","start":"2026-08-06T01:06:21.182328Z","end":"2026-08-06T01:06:25.412863Z","steps":["trace[536587180] 'agreement among raft nodes before linearized reading' (duration: 4.230476061s)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:06:25.412884Z","caller":"traceutil/trace.go:171","msg":"trace[1554985090] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:2785; }","duration":"2.230333539s","start":"2026-08-06T01:06:23.182543Z","end":"2026-08-06T01:06:25.412877Z","steps":["trace[1554985090] 'agreement among raft nodes before linearized reading' (duration: 2.230293438s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:25.412906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:23.182509Z","time spent":"2.230391101s","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-08-06T01:06:25.413106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.890913461s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-08-06T01:06:25.413127Z","caller":"traceutil/trace.go:171","msg":"trace[271070230] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2785; }","duration":"2.890992164s","start":"2026-08-06T01:06:22.522128Z","end":"2026-08-06T01:06:25.413121Z","steps":["trace[271070230] 'agreement among raft nodes before linearized reading' (duration: 2.890917632s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:25.413156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:22.522087Z","time spent":"2.891063825s","remote":"127.0.0.1:37138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-08-06T01:06:25.413174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.065957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-08-06T01:06:25.413199Z","caller":"traceutil/trace.go:171","msg":"trace[2082103338] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2785; }","duration":"464.105157ms","start":"2026-08-06T01:06:24.949087Z","end":"2026-08-06T01:06:25.413192Z","steps":["trace[2082103338] 'agreement among raft nodes before linearized reading' (duration: 464.007414ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:25.413218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:24.949079Z","time spent":"464.134988ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-08-06T01:06:25.413346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.395403729s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-08-06T01:06:25.413373Z","caller":"traceutil/trace.go:171","msg":"trace[822988924] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:2785; }","duration":"3.39544407s","start":"2026-08-06T01:06:22.017921Z","end":"2026-08-06T01:06:25.413365Z","steps":["trace[822988924] 'agreement among raft nodes before linearized reading' (duration: 3.395349198s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:25.413392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:22.017917Z","time spent":"3.395471152s","remote":"127.0.0.1:37108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1656,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"warn","ts":"2026-08-06T01:06:25.41342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.924481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18c911dd6558c7ab\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-08-06T01:06:25.413444Z","caller":"traceutil/trace.go:171","msg":"trace[1888099568] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18c911dd6558c7ab; range_end:; response_count:1; response_revision:2785; }","duration":"464.946472ms","start":"2026-08-06T01:06:24.948489Z","end":"2026-08-06T01:06:25.413435Z","steps":["trace[1888099568] 'agreement among raft nodes before linearized reading' (duration: 464.86424ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:25.413441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.075273ms","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-08-06T01:06:25.413473Z","caller":"traceutil/trace.go:171","msg":"trace[829996267] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:2785; }","duration":"466.141465ms","start":"2026-08-06T01:06:24.947321Z","end":"2026-08-06T01:06:25.413463Z","steps":["trace[829996267] 'agreement among raft nodes before linearized reading' (duration: 466.092443ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:25.413494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:24.947294Z","time spent":"466.193687ms","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":2,"response size":30,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"warn","ts":"2026-08-06T01:06:25.413496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:24.948475Z","time spent":"464.982703ms","remote":"127.0.0.1:37028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":804,"request content":"key:\"/registry/events/auth-system/keycloak-0.18c911dd6558c7ab\" "} {"level":"warn","ts":"2026-08-06T01:06:25.413599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.29775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T01:06:25.413621Z","caller":"traceutil/trace.go:171","msg":"trace[1258457992] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2785; }","duration":"466.371782ms","start":"2026-08-06T01:06:24.947243Z","end":"2026-08-06T01:06:25.413615Z","steps":["trace[1258457992] 'agreement among raft nodes before linearized reading' (duration: 466.331011ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:25.41364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:24.947222Z","time spent":"466.412672ms","remote":"127.0.0.1:37366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-08-06T01:06:25.413654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.39864365s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-06T01:06:25.413676Z","caller":"traceutil/trace.go:171","msg":"trace[1490761327] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2785; }","duration":"3.39868136s","start":"2026-08-06T01:06:22.014989Z","end":"2026-08-06T01:06:25.413671Z","steps":["trace[1490761327] 'agreement among raft nodes before linearized reading' (duration: 3.398493166s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:25.413694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:22.014981Z","time spent":"3.398708011s","remote":"127.0.0.1:37444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":330,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-08-06T01:06:25.412888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:21.182321Z","time spent":"4.230562853s","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-06T01:06:25.56482Z","caller":"traceutil/trace.go:171","msg":"trace[181163158] transaction","detail":"{read_only:false; response_revision:2797; number_of_response:1; }","duration":"112.767863ms","start":"2026-08-06T01:06:25.452014Z","end":"2026-08-06T01:06:25.564782Z","steps":["trace[181163158] 'process raft request' (duration: 44.572166ms)","trace[181163158] 'compare' (duration: 67.94804ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:06:31.955456Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3575189414622647720,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-06T01:06:32.208115Z","caller":"traceutil/trace.go:171","msg":"trace[1641232581] transaction","detail":"{read_only:false; response_revision:2821; number_of_response:1; }","duration":"784.717043ms","start":"2026-08-06T01:06:31.42337Z","end":"2026-08-06T01:06:32.208087Z","steps":["trace[1641232581] 'process raft request' (duration: 784.522088ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:32.208265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:31.423344Z","time spent":"784.842107ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:06:32.221569Z","caller":"traceutil/trace.go:171","msg":"trace[920713783] transaction","detail":"{read_only:false; response_revision:2822; number_of_response:1; }","duration":"792.210753ms","start":"2026-08-06T01:06:31.429339Z","end":"2026-08-06T01:06:32.22155Z","steps":["trace[920713783] 'process raft request' (duration: 792.030407ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:32.221683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:31.429321Z","time spent":"792.300756ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:06:32.221772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.365831ms","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-08-06T01:06:32.221822Z","caller":"traceutil/trace.go:171","msg":"trace[1958642021] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2823; }","duration":"766.465874ms","start":"2026-08-06T01:06:31.455343Z","end":"2026-08-06T01:06:32.221809Z","steps":["trace[1958642021] 'agreement among raft nodes before linearized reading' (duration: 766.264089ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:06:32.221564Z","caller":"traceutil/trace.go:171","msg":"trace[1052153617] linearizableReadLoop","detail":"{readStateIndex:2930; appliedIndex:2927; }","duration":"766.132964ms","start":"2026-08-06T01:06:31.455398Z","end":"2026-08-06T01:06:32.221531Z","steps":["trace[1052153617] 'read index received' (duration: 752.633986ms)","trace[1052153617] 'applied index is now lower than readState.Index' (duration: 13.497648ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:06:32.221926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:31.45532Z","time spent":"766.596817ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-06T01:06:32.222021Z","caller":"traceutil/trace.go:171","msg":"trace[1201536913] transaction","detail":"{read_only:false; response_revision:2823; number_of_response:1; }","duration":"792.546902ms","start":"2026-08-06T01:06:31.429463Z","end":"2026-08-06T01:06:32.22201Z","steps":["trace[1201536913] 'process raft request' (duration: 792.021737ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:32.222088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:31.429389Z","time spent":"792.660016ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:06:32.222118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.145144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-06T01:06:32.222146Z","caller":"traceutil/trace.go:171","msg":"trace[513846176] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2823; }","duration":"766.176575ms","start":"2026-08-06T01:06:31.455962Z","end":"2026-08-06T01:06:32.222139Z","steps":["trace[513846176] 'agreement among raft nodes before linearized reading' (duration: 766.099803ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:32.22217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:31.455902Z","time spent":"766.263877ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-06T01:06:32.222287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.08192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-node-0.18c911ddec3a8008\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-08-06T01:06:32.22231Z","caller":"traceutil/trace.go:171","msg":"trace[691650619] range","detail":"{range_begin:/registry/events/openstack/valkey-node-0.18c911ddec3a8008; range_end:; response_count:1; response_revision:2823; }","duration":"148.134142ms","start":"2026-08-06T01:06:32.07417Z","end":"2026-08-06T01:06:32.222304Z","steps":["trace[691650619] 'agreement among raft nodes before linearized reading' (duration: 148.0722ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:32.222426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.952362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:06:32.222448Z","caller":"traceutil/trace.go:171","msg":"trace[1925926450] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2823; }","duration":"273.021504ms","start":"2026-08-06T01:06:31.949421Z","end":"2026-08-06T01:06:32.222442Z","steps":["trace[1925926450] 'agreement among raft nodes before linearized reading' (duration: 272.987984ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:32.222592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.029081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-06T01:06:32.222619Z","caller":"traceutil/trace.go:171","msg":"trace[2002774524] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2823; }","duration":"766.099484ms","start":"2026-08-06T01:06:31.456511Z","end":"2026-08-06T01:06:32.22261Z","steps":["trace[2002774524] 'agreement among raft nodes before linearized reading' (duration: 766.027061ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:32.222637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:31.456479Z","time spent":"766.153644ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-06T01:06:32.222708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.663229ms","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-08-06T01:06:32.222757Z","caller":"traceutil/trace.go:171","msg":"trace[1919716156] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2823; }","duration":"766.73402ms","start":"2026-08-06T01:06:31.456009Z","end":"2026-08-06T01:06:32.222743Z","steps":["trace[1919716156] 'agreement among raft nodes before linearized reading' (duration: 766.617737ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:32.222795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:31.456Z","time spent":"766.787132ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-06T01:06:32.222939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.524696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-06T01:06:32.222967Z","caller":"traceutil/trace.go:171","msg":"trace[286149260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2823; }","duration":"766.579506ms","start":"2026-08-06T01:06:31.456381Z","end":"2026-08-06T01:06:32.222961Z","steps":["trace[286149260] 'agreement among raft nodes before linearized reading' (duration: 766.514725ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:32.222986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:31.456364Z","time spent":"766.617298ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-06T01:06:32.22312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.588207ms","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-08-06T01:06:32.223161Z","caller":"traceutil/trace.go:171","msg":"trace[1242560375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2823; }","duration":"766.667599ms","start":"2026-08-06T01:06:31.456483Z","end":"2026-08-06T01:06:32.223151Z","steps":["trace[1242560375] 'agreement among raft nodes before linearized reading' (duration: 766.552826ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:32.223196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:31.456467Z","time spent":"766.72189ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-06T01:06:32.549189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.458542ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T01:06:32.549332Z","caller":"traceutil/trace.go:171","msg":"trace[2070980215] transaction","detail":"{read_only:false; response_revision:2824; number_of_response:1; }","duration":"324.143574ms","start":"2026-08-06T01:06:32.225162Z","end":"2026-08-06T01:06:32.549305Z","steps":["trace[2070980215] 'process raft request' (duration: 62.392395ms)","trace[2070980215] 'compare' (duration: 261.07477ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:06:32.549402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:32.225147Z","time spent":"324.221937ms","remote":"127.0.0.1:37028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:06:32.574066Z","caller":"traceutil/trace.go:171","msg":"trace[2064892456] transaction","detail":"{read_only:false; response_revision:2826; number_of_response:1; }","duration":"343.764773ms","start":"2026-08-06T01:06:32.230247Z","end":"2026-08-06T01:06:32.574012Z","steps":["trace[2064892456] 'process raft request' (duration: 343.669101ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:32.574364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:32.230244Z","time spent":"343.926797ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:06:32.574616Z","caller":"traceutil/trace.go:171","msg":"trace[1832851164] transaction","detail":"{read_only:false; response_revision:2827; number_of_response:1; }","duration":"344.296448ms","start":"2026-08-06T01:06:32.230277Z","end":"2026-08-06T01:06:32.574573Z","steps":["trace[1832851164] 'process raft request' (duration: 343.66995ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:06:32.574782Z","caller":"traceutil/trace.go:171","msg":"trace[1319595024] transaction","detail":"{read_only:false; response_revision:2825; number_of_response:1; }","duration":"344.588966ms","start":"2026-08-06T01:06:32.230173Z","end":"2026-08-06T01:06:32.574762Z","steps":["trace[1319595024] 'process raft request' (duration: 343.568227ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:32.574818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:32.230272Z","time spent":"344.441381ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:06:32.574897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:32.230153Z","time spent":"344.690739ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:06:32.575194Z","caller":"traceutil/trace.go:171","msg":"trace[252792382] transaction","detail":"{read_only:false; response_revision:2828; number_of_response:1; }","duration":"344.839363ms","start":"2026-08-06T01:06:32.230347Z","end":"2026-08-06T01:06:32.575186Z","steps":["trace[252792382] 'process raft request' (duration: 344.117923ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:32.575278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:32.230343Z","time spent":"344.882474ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:06:32.575489Z","caller":"traceutil/trace.go:171","msg":"trace[1851102865] transaction","detail":"{read_only:false; response_revision:2830; number_of_response:1; }","duration":"344.824623ms","start":"2026-08-06T01:06:32.23064Z","end":"2026-08-06T01:06:32.575465Z","steps":["trace[1851102865] 'process raft request' (duration: 344.689139ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:06:32.575549Z","caller":"traceutil/trace.go:171","msg":"trace[1396777976] transaction","detail":"{read_only:false; response_revision:2829; number_of_response:1; }","duration":"345.095531ms","start":"2026-08-06T01:06:32.230426Z","end":"2026-08-06T01:06:32.575522Z","steps":["trace[1396777976] 'process raft request' (duration: 344.714739ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:32.575658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:32.230422Z","time spent":"345.179483ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:06:32.579217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:32.230613Z","time spent":"348.504906ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:09:19.164796Z","caller":"traceutil/trace.go:171","msg":"trace[1682940602] transaction","detail":"{read_only:false; response_revision:3900; number_of_response:1; }","duration":"231.070684ms","start":"2026-08-06T01:09:18.933703Z","end":"2026-08-06T01:09:19.164773Z","steps":["trace[1682940602] 'process raft request' (duration: 230.681833ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:19.164843Z","caller":"traceutil/trace.go:171","msg":"trace[1607174250] transaction","detail":"{read_only:false; response_revision:3901; number_of_response:1; }","duration":"229.958482ms","start":"2026-08-06T01:09:18.934855Z","end":"2026-08-06T01:09:19.164814Z","steps":["trace[1607174250] 'process raft request' (duration: 229.854719ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:19.164779Z","caller":"traceutil/trace.go:171","msg":"trace[939078444] linearizableReadLoop","detail":"{readStateIndex:4048; appliedIndex:4046; }","duration":"222.851873ms","start":"2026-08-06T01:09:18.941905Z","end":"2026-08-06T01:09:19.164756Z","steps":["trace[939078444] 'read index received' (duration: 222.612075ms)","trace[939078444] 'applied index is now lower than readState.Index' (duration: 238.908µs)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:09:19.165464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.541162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12209"} {"level":"info","ts":"2026-08-06T01:09:19.165528Z","caller":"traceutil/trace.go:171","msg":"trace[566977702] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3901; }","duration":"223.642885ms","start":"2026-08-06T01:09:18.941872Z","end":"2026-08-06T01:09:19.165515Z","steps":["trace[566977702] 'agreement among raft nodes before linearized reading' (duration: 223.041118ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:19.166245Z","caller":"traceutil/trace.go:171","msg":"trace[179155204] transaction","detail":"{read_only:false; response_revision:3902; number_of_response:1; }","duration":"134.130039ms","start":"2026-08-06T01:09:19.03209Z","end":"2026-08-06T01:09:19.16622Z","steps":["trace[179155204] 'process raft request' (duration: 133.997716ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:10:49.697378Z","caller":"traceutil/trace.go:171","msg":"trace[1539775142] transaction","detail":"{read_only:false; response_revision:5132; number_of_response:1; }","duration":"162.484621ms","start":"2026-08-06T01:10:49.534845Z","end":"2026-08-06T01:10:49.697329Z","steps":["trace[1539775142] 'process raft request' (duration: 111.904841ms)","trace[1539775142] 'compare' (duration: 50.389245ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:10:49.907793Z","caller":"traceutil/trace.go:171","msg":"trace[1973280781] linearizableReadLoop","detail":"{readStateIndex:5328; appliedIndex:5326; }","duration":"348.701ms","start":"2026-08-06T01:10:49.559053Z","end":"2026-08-06T01:10:49.907754Z","steps":["trace[1973280781] 'read index received' (duration: 87.726284ms)","trace[1973280781] 'applied index is now lower than readState.Index' (duration: 260.973556ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:10:49.908012Z","caller":"traceutil/trace.go:171","msg":"trace[1074560825] transaction","detail":"{read_only:false; response_revision:5133; number_of_response:1; }","duration":"359.955625ms","start":"2026-08-06T01:10:49.548039Z","end":"2026-08-06T01:10:49.907995Z","steps":["trace[1074560825] 'process raft request' (duration: 359.522632ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:10:49.908298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:10:49.548011Z","time spent":"360.107449ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:10:49.908425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.329988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-974585af-5b1d-4ecf-a065-2cf9dbe8028e\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-08-06T01:10:49.908527Z","caller":"traceutil/trace.go:171","msg":"trace[298789630] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-974585af-5b1d-4ecf-a065-2cf9dbe8028e; range_end:; response_count:1; response_revision:5133; }","duration":"349.485381ms","start":"2026-08-06T01:10:49.559016Z","end":"2026-08-06T01:10:49.908501Z","steps":["trace[298789630] 'agreement among raft nodes before linearized reading' (duration: 349.243635ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:10:49.908575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:10:49.559002Z","time spent":"349.562663ms","remote":"127.0.0.1:37128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3623,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-974585af-5b1d-4ecf-a065-2cf9dbe8028e\" "} {"level":"warn","ts":"2026-08-06T01:10:50.402807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.050798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-974585af-5b1d-4ecf-a065-2cf9dbe8028e\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-08-06T01:10:50.402899Z","caller":"traceutil/trace.go:171","msg":"trace[2127567503] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-974585af-5b1d-4ecf-a065-2cf9dbe8028e; range_end:; response_count:1; response_revision:5139; }","duration":"173.198673ms","start":"2026-08-06T01:10:50.229679Z","end":"2026-08-06T01:10:50.402878Z","steps":["trace[2127567503] 'range keys from in-memory index tree' (duration: 172.889994ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:10:50.403054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.9042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-t8lb4\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-08-06T01:10:50.403121Z","caller":"traceutil/trace.go:171","msg":"trace[1109536413] range","detail":"{range_begin:/registry/pods/openstack/keepalived-t8lb4; range_end:; response_count:1; response_revision:5139; }","duration":"201.008823ms","start":"2026-08-06T01:10:50.202097Z","end":"2026-08-06T01:10:50.403106Z","steps":["trace[1109536413] 'range keys from in-memory index tree' (duration: 200.734715ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:10:50.403479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.786493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-06T01:10:50.403516Z","caller":"traceutil/trace.go:171","msg":"trace[1780630858] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5139; }","duration":"202.870155ms","start":"2026-08-06T01:10:50.200634Z","end":"2026-08-06T01:10:50.403505Z","steps":["trace[1780630858] 'range keys from in-memory index tree' (duration: 202.196527ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:11:02.206334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.062111ms","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-08-06T01:11:02.206447Z","caller":"traceutil/trace.go:171","msg":"trace[1049319540] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5378; }","duration":"104.227996ms","start":"2026-08-06T01:11:02.102189Z","end":"2026-08-06T01:11:02.206417Z","steps":["trace[1049319540] 'range keys from in-memory index tree' (duration: 103.936578ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:11:02.350834Z","caller":"traceutil/trace.go:171","msg":"trace[1659660503] transaction","detail":"{read_only:false; response_revision:5380; number_of_response:1; }","duration":"138.749885ms","start":"2026-08-06T01:11:02.211868Z","end":"2026-08-06T01:11:02.350617Z","steps":["trace[1659660503] 'process raft request' (duration: 96.491689ms)","trace[1659660503] 'compare' (duration: 42.004599ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:11:02.351082Z","caller":"traceutil/trace.go:171","msg":"trace[1759532517] transaction","detail":"{read_only:false; response_revision:5381; number_of_response:1; }","duration":"138.321633ms","start":"2026-08-06T01:11:02.212715Z","end":"2026-08-06T01:11:02.351037Z","steps":["trace[1759532517] 'process raft request' (duration: 137.840929ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:11:02.351174Z","caller":"traceutil/trace.go:171","msg":"trace[680854787] transaction","detail":"{read_only:false; response_revision:5382; number_of_response:1; }","duration":"138.316403ms","start":"2026-08-06T01:11:02.212829Z","end":"2026-08-06T01:11:02.351146Z","steps":["trace[680854787] 'process raft request' (duration: 138.111167ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:11:02.351344Z","caller":"traceutil/trace.go:171","msg":"trace[1612363201] transaction","detail":"{read_only:false; response_revision:5383; number_of_response:1; }","duration":"136.299297ms","start":"2026-08-06T01:11:02.215032Z","end":"2026-08-06T01:11:02.351331Z","steps":["trace[1612363201] 'process raft request' (duration: 136.06032ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:11:02.564938Z","caller":"traceutil/trace.go:171","msg":"trace[2061302891] transaction","detail":"{read_only:false; response_revision:5387; number_of_response:1; }","duration":"123.761405ms","start":"2026-08-06T01:11:02.441123Z","end":"2026-08-06T01:11:02.564885Z","steps":["trace[2061302891] 'process raft request' (duration: 123.463747ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:11:02.564944Z","caller":"traceutil/trace.go:171","msg":"trace[2131757721] transaction","detail":"{read_only:false; response_revision:5388; number_of_response:1; }","duration":"123.758654ms","start":"2026-08-06T01:11:02.441147Z","end":"2026-08-06T01:11:02.564906Z","steps":["trace[2131757721] 'process raft request' (duration: 123.62943ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:11:02.565245Z","caller":"traceutil/trace.go:171","msg":"trace[368942420] transaction","detail":"{read_only:false; response_revision:5389; number_of_response:1; }","duration":"122.303153ms","start":"2026-08-06T01:11:02.442887Z","end":"2026-08-06T01:11:02.56519Z","steps":["trace[368942420] 'process raft request' (duration: 121.956564ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:11:04.520898Z","caller":"traceutil/trace.go:171","msg":"trace[2056030533] transaction","detail":"{read_only:false; response_revision:5399; number_of_response:1; }","duration":"159.624921ms","start":"2026-08-06T01:11:04.361239Z","end":"2026-08-06T01:11:04.520864Z","steps":["trace[2056030533] 'process raft request' (duration: 159.451426ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:11:04.605875Z","caller":"traceutil/trace.go:171","msg":"trace[654275418] transaction","detail":"{read_only:false; response_revision:5400; number_of_response:1; }","duration":"244.474294ms","start":"2026-08-06T01:11:04.36136Z","end":"2026-08-06T01:11:04.605835Z","steps":["trace[654275418] 'process raft request' (duration: 244.156385ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:11:04.60591Z","caller":"traceutil/trace.go:171","msg":"trace[2036734397] transaction","detail":"{read_only:false; response_revision:5401; number_of_response:1; }","duration":"244.332479ms","start":"2026-08-06T01:11:04.36155Z","end":"2026-08-06T01:11:04.605882Z","steps":["trace[2036734397] 'process raft request' (duration: 244.181166ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:11:04.606039Z","caller":"traceutil/trace.go:171","msg":"trace[1280023770] transaction","detail":"{read_only:false; response_revision:5402; number_of_response:1; }","duration":"242.746294ms","start":"2026-08-06T01:11:04.363246Z","end":"2026-08-06T01:11:04.605993Z","steps":["trace[1280023770] 'process raft request' (duration: 242.530128ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:11:04.60612Z","caller":"traceutil/trace.go:171","msg":"trace[722556088] transaction","detail":"{read_only:false; response_revision:5403; number_of_response:1; }","duration":"224.268826ms","start":"2026-08-06T01:11:04.381842Z","end":"2026-08-06T01:11:04.60611Z","steps":["trace[722556088] 'process raft request' (duration: 223.975858ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:11:04.60612Z","caller":"traceutil/trace.go:171","msg":"trace[747800240] linearizableReadLoop","detail":"{readStateIndex:5605; appliedIndex:5601; }","duration":"227.624939ms","start":"2026-08-06T01:11:04.37847Z","end":"2026-08-06T01:11:04.606095Z","steps":["trace[747800240] 'read index received' (duration: 142.263624ms)","trace[747800240] 'applied index is now lower than readState.Index' (duration: 85.359805ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:11:04.606388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.897127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/goldpinger-cd5hk\" ","response":"range_response_count:1 size:4991"} {"level":"info","ts":"2026-08-06T01:11:04.606428Z","caller":"traceutil/trace.go:171","msg":"trace[442780335] range","detail":"{range_begin:/registry/pods/monitoring/goldpinger-cd5hk; range_end:; response_count:1; response_revision:5403; }","duration":"227.97094ms","start":"2026-08-06T01:11:04.378445Z","end":"2026-08-06T01:11:04.606416Z","steps":["trace[442780335] 'agreement among raft nodes before linearized reading' (duration: 227.793486ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:11:11.527095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.155917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:11:11.527245Z","caller":"traceutil/trace.go:171","msg":"trace[1303332738] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:5565; }","duration":"299.391285ms","start":"2026-08-06T01:11:11.227825Z","end":"2026-08-06T01:11:11.527216Z","steps":["trace[1303332738] 'count revisions from in-memory index tree' (duration: 299.025496ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:11:11.527582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.305192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-56508df5-7a75-4294-b57e-2185d339dabf\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-08-06T01:11:11.527692Z","caller":"traceutil/trace.go:171","msg":"trace[1087302103] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-56508df5-7a75-4294-b57e-2185d339dabf; range_end:; response_count:1; response_revision:5565; }","duration":"258.526638ms","start":"2026-08-06T01:11:11.269142Z","end":"2026-08-06T01:11:11.527669Z","steps":["trace[1087302103] 'range keys from in-memory index tree' (duration: 257.772177ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:11:11.899847Z","caller":"traceutil/trace.go:171","msg":"trace[1430308636] linearizableReadLoop","detail":"{readStateIndex:5773; appliedIndex:5772; }","duration":"344.486122ms","start":"2026-08-06T01:11:11.555328Z","end":"2026-08-06T01:11:11.899814Z","steps":["trace[1430308636] 'read index received' (duration: 344.446091ms)","trace[1430308636] 'applied index is now lower than readState.Index' (duration: 38.851µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:11:11.899982Z","caller":"traceutil/trace.go:171","msg":"trace[1007201915] transaction","detail":"{read_only:false; response_revision:5567; number_of_response:1; }","duration":"365.247074ms","start":"2026-08-06T01:11:11.534723Z","end":"2026-08-06T01:11:11.89997Z","steps":["trace[1007201915] 'process raft request' (duration: 364.903104ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:11:11.900121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:11:11.534686Z","time spent":"365.330736ms","remote":"127.0.0.1:37128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3747,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:11:11.900405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.050757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-08-06T01:11:11.900527Z","caller":"traceutil/trace.go:171","msg":"trace[383631953] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5567; }","duration":"345.230502ms","start":"2026-08-06T01:11:11.555269Z","end":"2026-08-06T01:11:11.900499Z","steps":["trace[383631953] 'agreement among raft nodes before linearized reading' (duration: 344.939294ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:11:11.90059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:11:11.555241Z","time spent":"345.335826ms","remote":"127.0.0.1:37108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1804,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" "} {"level":"info","ts":"2026-08-06T01:11:12.345564Z","caller":"traceutil/trace.go:171","msg":"trace[1195566271] linearizableReadLoop","detail":"{readStateIndex:5775; appliedIndex:5774; }","duration":"445.59589ms","start":"2026-08-06T01:11:11.89993Z","end":"2026-08-06T01:11:12.345526Z","steps":["trace[1195566271] 'read index received' (duration: 445.579668ms)","trace[1195566271] 'applied index is now lower than readState.Index' (duration: 14.242µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:11:12.345659Z","caller":"traceutil/trace.go:171","msg":"trace[331656750] transaction","detail":"{read_only:false; response_revision:5568; number_of_response:1; }","duration":"577.04453ms","start":"2026-08-06T01:11:11.768579Z","end":"2026-08-06T01:11:12.345623Z","steps":["trace[331656750] 'process raft request' (duration: 576.722021ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:11:12.345557Z","caller":"traceutil/trace.go:171","msg":"trace[1681762291] transaction","detail":"{read_only:false; response_revision:5569; number_of_response:1; }","duration":"545.020851ms","start":"2026-08-06T01:11:11.800514Z","end":"2026-08-06T01:11:12.345534Z","steps":["trace[1681762291] 'process raft request' (duration: 544.966269ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:11:12.345821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.042934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12209"} {"level":"warn","ts":"2026-08-06T01:11:12.345822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:11:11.768552Z","time spent":"577.154383ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:11:12.345787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.787259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:11:12.345865Z","caller":"traceutil/trace.go:171","msg":"trace[1002040226] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5569; }","duration":"777.119627ms","start":"2026-08-06T01:11:11.568734Z","end":"2026-08-06T01:11:12.345853Z","steps":["trace[1002040226] 'agreement among raft nodes before linearized reading' (duration: 776.939391ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:11:12.345896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:11:11.568708Z","time spent":"777.179867ms","remote":"127.0.0.1:37128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12232,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-06T01:11:12.3459Z","caller":"traceutil/trace.go:171","msg":"trace[663737555] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:5569; }","duration":"718.979144ms","start":"2026-08-06T01:11:11.626893Z","end":"2026-08-06T01:11:12.345872Z","steps":["trace[663737555] 'agreement among raft nodes before linearized reading' (duration: 718.761108ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:11:12.345945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:11:11.800477Z","time spent":"545.334729ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:11:12.34596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:11:11.626866Z","time spent":"719.080327ms","remote":"127.0.0.1:34938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-06T01:11:12.34693Z","caller":"traceutil/trace.go:171","msg":"trace[802634844] transaction","detail":"{read_only:false; response_revision:5570; number_of_response:1; }","duration":"311.324259ms","start":"2026-08-06T01:11:12.035569Z","end":"2026-08-06T01:11:12.346893Z","steps":["trace[802634844] 'process raft request' (duration: 311.080853ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:11:12.347151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:11:12.035533Z","time spent":"311.498066ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:11:12.347221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.524463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-0\" ","response":"range_response_count:1 size:5834"} {"level":"info","ts":"2026-08-06T01:11:12.34728Z","caller":"traceutil/trace.go:171","msg":"trace[1465868410] range","detail":"{range_begin:/registry/pods/monitoring/loki-0; range_end:; response_count:1; response_revision:5571; }","duration":"442.586475ms","start":"2026-08-06T01:11:11.904679Z","end":"2026-08-06T01:11:12.347265Z","steps":["trace[1465868410] 'agreement among raft nodes before linearized reading' (duration: 442.39237ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:11:12.347316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:11:11.904632Z","time spent":"442.677287ms","remote":"127.0.0.1:37128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":5857,"request content":"key:\"/registry/pods/monitoring/loki-0\" "} {"level":"info","ts":"2026-08-06T01:11:12.347479Z","caller":"traceutil/trace.go:171","msg":"trace[1993254698] transaction","detail":"{read_only:false; response_revision:5571; number_of_response:1; }","duration":"301.184024ms","start":"2026-08-06T01:11:12.046244Z","end":"2026-08-06T01:11:12.347428Z","steps":["trace[1993254698] 'process raft request' (duration: 300.583648ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:11:12.347588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.781931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-974585af-5b1d-4ecf-a065-2cf9dbe8028e\" ","response":"range_response_count:1 size:1275"} {"level":"info","ts":"2026-08-06T01:11:12.347638Z","caller":"traceutil/trace.go:171","msg":"trace[2048023313] range","detail":"{range_begin:/registry/persistentvolumes/pvc-974585af-5b1d-4ecf-a065-2cf9dbe8028e; range_end:; response_count:1; response_revision:5571; }","duration":"442.865493ms","start":"2026-08-06T01:11:11.904757Z","end":"2026-08-06T01:11:12.347623Z","steps":["trace[2048023313] 'agreement among raft nodes before linearized reading' (duration: 442.73587ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:11:12.347505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.503309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T01:11:12.347673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:11:11.90474Z","time spent":"442.925084ms","remote":"127.0.0.1:37106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1298,"request content":"key:\"/registry/persistentvolumes/pvc-974585af-5b1d-4ecf-a065-2cf9dbe8028e\" "} {"level":"info","ts":"2026-08-06T01:11:12.3477Z","caller":"traceutil/trace.go:171","msg":"trace[189231605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5571; }","duration":"397.755587ms","start":"2026-08-06T01:11:11.949928Z","end":"2026-08-06T01:11:12.347683Z","steps":["trace[189231605] 'agreement among raft nodes before linearized reading' (duration: 397.501899ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:11:12.347772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:11:12.046221Z","time spent":"301.377712ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:11:12.347808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:11:11.949906Z","time spent":"397.8846ms","remote":"127.0.0.1:36940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-06T01:11:54.915814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.260684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:11:54.915936Z","caller":"traceutil/trace.go:171","msg":"trace[1000844511] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:6000; }","duration":"116.455651ms","start":"2026-08-06T01:11:54.799454Z","end":"2026-08-06T01:11:54.91591Z","steps":["trace[1000844511] 'range keys from in-memory index tree' (duration: 116.162871ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:11:54.916069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.243117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-08-06T01:11:54.916113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.482071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:11:54.916203Z","caller":"traceutil/trace.go:171","msg":"trace[537750015] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6000; }","duration":"228.457024ms","start":"2026-08-06T01:11:54.68772Z","end":"2026-08-06T01:11:54.916177Z","steps":["trace[537750015] 'range keys from in-memory index tree' (duration: 227.900528ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:11:54.916254Z","caller":"traceutil/trace.go:171","msg":"trace[1514590470] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:6000; }","duration":"116.711505ms","start":"2026-08-06T01:11:54.799514Z","end":"2026-08-06T01:11:54.916226Z","steps":["trace[1514590470] 'range keys from in-memory index tree' (duration: 116.173572ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:11:54.916082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.541859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6159"} {"level":"info","ts":"2026-08-06T01:11:54.916347Z","caller":"traceutil/trace.go:171","msg":"trace[1108608897] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:6000; }","duration":"112.834167ms","start":"2026-08-06T01:11:54.803481Z","end":"2026-08-06T01:11:54.916316Z","steps":["trace[1108608897] 'range keys from in-memory index tree' (duration: 112.296232ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:11:54.916439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.575134ms","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-08-06T01:11:54.916624Z","caller":"traceutil/trace.go:171","msg":"trace[365322983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6000; }","duration":"100.78782ms","start":"2026-08-06T01:11:54.8158Z","end":"2026-08-06T01:11:54.916587Z","steps":["trace[365322983] 'range keys from in-memory index tree' (duration: 100.425619ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:11:55.128524Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2952} {"level":"info","ts":"2026-08-06T01:11:55.222061Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2952,"took":"92.146406ms","hash":1549735548,"current-db-size-bytes":57683968,"current-db-size":"58 MB","current-db-size-in-use-bytes":33390592,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-06T01:11:55.222145Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1549735548,"revision":2952,"compact-revision":-1} {"level":"info","ts":"2026-08-06T01:12:05.177946Z","caller":"traceutil/trace.go:171","msg":"trace[1014312565] transaction","detail":"{read_only:false; response_revision:6076; number_of_response:1; }","duration":"118.970499ms","start":"2026-08-06T01:12:05.058934Z","end":"2026-08-06T01:12:05.177904Z","steps":["trace[1014312565] 'process raft request' (duration: 73.76728ms)","trace[1014312565] 'compare' (duration: 44.937012ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:12:53.025722Z","caller":"traceutil/trace.go:171","msg":"trace[695136918] transaction","detail":"{read_only:false; response_revision:6426; number_of_response:1; }","duration":"165.703841ms","start":"2026-08-06T01:12:52.859986Z","end":"2026-08-06T01:12:53.02569Z","steps":["trace[695136918] 'process raft request' (duration: 165.502016ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:12:53.424204Z","caller":"traceutil/trace.go:171","msg":"trace[383308014] transaction","detail":"{read_only:false; response_revision:6427; number_of_response:1; }","duration":"124.281136ms","start":"2026-08-06T01:12:53.299898Z","end":"2026-08-06T01:12:53.424179Z","steps":["trace[383308014] 'process raft request' (duration: 124.123501ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:12:53.42894Z","caller":"traceutil/trace.go:171","msg":"trace[658291720] transaction","detail":"{read_only:false; response_revision:6428; number_of_response:1; }","duration":"128.73151ms","start":"2026-08-06T01:12:53.300168Z","end":"2026-08-06T01:12:53.428899Z","steps":["trace[658291720] 'process raft request' (duration: 128.447643ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:12:53.428984Z","caller":"traceutil/trace.go:171","msg":"trace[487849411] transaction","detail":"{read_only:false; response_revision:6429; number_of_response:1; }","duration":"125.519231ms","start":"2026-08-06T01:12:53.303414Z","end":"2026-08-06T01:12:53.428933Z","steps":["trace[487849411] 'process raft request' (duration: 125.353646ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:12:53.429124Z","caller":"traceutil/trace.go:171","msg":"trace[588225808] linearizableReadLoop","detail":"{readStateIndex:6659; appliedIndex:6656; }","duration":"123.347289ms","start":"2026-08-06T01:12:53.30575Z","end":"2026-08-06T01:12:53.429097Z","steps":["trace[588225808] 'read index received' (duration: 118.308737ms)","trace[588225808] 'applied index is now lower than readState.Index' (duration: 5.036132ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:12:53.429335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.567615ms","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-08-06T01:12:53.429369Z","caller":"traceutil/trace.go:171","msg":"trace[202292526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6430; }","duration":"123.641157ms","start":"2026-08-06T01:12:53.305717Z","end":"2026-08-06T01:12:53.429359Z","steps":["trace[202292526] 'agreement among raft nodes before linearized reading' (duration: 123.478382ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:12:53.42938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.652225ms","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-08-06T01:12:53.429433Z","caller":"traceutil/trace.go:171","msg":"trace[755070671] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6430; }","duration":"103.762368ms","start":"2026-08-06T01:12:53.325658Z","end":"2026-08-06T01:12:53.429421Z","steps":["trace[755070671] 'agreement among raft nodes before linearized reading' (duration: 103.562953ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:12:54.681215Z","caller":"traceutil/trace.go:171","msg":"trace[1953359653] transaction","detail":"{read_only:false; response_revision:6438; number_of_response:1; }","duration":"241.825391ms","start":"2026-08-06T01:12:54.439362Z","end":"2026-08-06T01:12:54.681187Z","steps":["trace[1953359653] 'process raft request' (duration: 241.578144ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:12:55.303462Z","caller":"traceutil/trace.go:171","msg":"trace[216654044] linearizableReadLoop","detail":"{readStateIndex:6673; appliedIndex:6672; }","duration":"479.049694ms","start":"2026-08-06T01:12:54.824387Z","end":"2026-08-06T01:12:55.303437Z","steps":["trace[216654044] 'read index received' (duration: 478.89843ms)","trace[216654044] 'applied index is now lower than readState.Index' (duration: 149.724µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:12:55.303617Z","caller":"traceutil/trace.go:171","msg":"trace[334254533] transaction","detail":"{read_only:false; response_revision:6441; number_of_response:1; }","duration":"487.224624ms","start":"2026-08-06T01:12:54.816328Z","end":"2026-08-06T01:12:55.303553Z","steps":["trace[334254533] 'process raft request' (duration: 486.929806ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:12:55.303714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.301781ms","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-08-06T01:12:55.303824Z","caller":"traceutil/trace.go:171","msg":"trace[114374344] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:6441; }","duration":"479.480406ms","start":"2026-08-06T01:12:54.824317Z","end":"2026-08-06T01:12:55.303798Z","steps":["trace[114374344] 'agreement among raft nodes before linearized reading' (duration: 479.250029ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:12:55.303868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:12:54.824282Z","time spent":"479.578149ms","remote":"127.0.0.1:37394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-08-06T01:12:55.303906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:12:54.816299Z","time spent":"487.44501ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:12:55.308561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.734109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:12:55.308603Z","caller":"traceutil/trace.go:171","msg":"trace[578172918] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6442; }","duration":"129.813311ms","start":"2026-08-06T01:12:55.17878Z","end":"2026-08-06T01:12:55.308593Z","steps":["trace[578172918] 'agreement among raft nodes before linearized reading' (duration: 129.74805ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:12:55.309Z","caller":"traceutil/trace.go:171","msg":"trace[714155448] transaction","detail":"{read_only:false; response_revision:6442; number_of_response:1; }","duration":"276.928719ms","start":"2026-08-06T01:12:55.03205Z","end":"2026-08-06T01:12:55.308979Z","steps":["trace[714155448] 'process raft request' (duration: 276.309241ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:04.908555Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.348377014s","expected-duration":"1s"} {"level":"info","ts":"2026-08-06T01:13:04.914577Z","caller":"traceutil/trace.go:171","msg":"trace[1336519850] transaction","detail":"{read_only:false; response_revision:6497; number_of_response:1; }","duration":"3.354465856s","start":"2026-08-06T01:13:01.560081Z","end":"2026-08-06T01:13:04.914547Z","steps":["trace[1336519850] 'process raft request' (duration: 3.35427139s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:04.914769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:01.560066Z","time spent":"3.354587419s","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:13:04.942133Z","caller":"traceutil/trace.go:171","msg":"trace[380366357] transaction","detail":"{read_only:false; response_revision:6498; number_of_response:1; }","duration":"3.380745258s","start":"2026-08-06T01:13:01.561362Z","end":"2026-08-06T01:13:04.942107Z","steps":["trace[380366357] 'process raft request' (duration: 3.379931046s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:04.942274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:01.561349Z","time spent":"3.380844691s","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:16:55.134729Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6008} {"level":"info","ts":"2026-08-06T01:16:55.236238Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6008,"took":"99.167843ms","hash":3856995656,"current-db-size-bytes":57683968,"current-db-size":"58 MB","current-db-size-in-use-bytes":21778432,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-08-06T01:16:55.23632Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3856995656,"revision":6008,"compact-revision":2952} {"level":"info","ts":"2026-08-06T01:17:15.000118Z","caller":"traceutil/trace.go:171","msg":"trace[736082042] transaction","detail":"{read_only:false; response_revision:8361; number_of_response:1; }","duration":"246.569984ms","start":"2026-08-06T01:17:14.753522Z","end":"2026-08-06T01:17:15.000092Z","steps":["trace[736082042] 'process raft request' (duration: 246.353628ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:17:15.227356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.355428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-08-06T01:17:15.227475Z","caller":"traceutil/trace.go:171","msg":"trace[2062810499] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:8361; }","duration":"105.539812ms","start":"2026-08-06T01:17:15.121907Z","end":"2026-08-06T01:17:15.227447Z","steps":["trace[2062810499] 'range keys from in-memory index tree' (duration: 105.1227ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:17:27.010952Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3575189414622661964,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-06T01:17:27.223808Z","caller":"traceutil/trace.go:171","msg":"trace[1311150593] linearizableReadLoop","detail":"{readStateIndex:8717; appliedIndex:8715; }","duration":"713.753611ms","start":"2026-08-06T01:17:26.510023Z","end":"2026-08-06T01:17:27.223777Z","steps":["trace[1311150593] 'read index received' (duration: 713.529145ms)","trace[1311150593] 'applied index is now lower than readState.Index' (duration: 216.626µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:17:27.224214Z","caller":"traceutil/trace.go:171","msg":"trace[619095700] transaction","detail":"{read_only:false; response_revision:8418; number_of_response:1; }","duration":"803.19708ms","start":"2026-08-06T01:17:26.420999Z","end":"2026-08-06T01:17:27.224196Z","steps":["trace[619095700] 'process raft request' (duration: 802.532521ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:17:27.22435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:17:26.420951Z","time spent":"803.307032ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:17:27.224554Z","caller":"traceutil/trace.go:171","msg":"trace[972367727] transaction","detail":"{read_only:false; response_revision:8419; number_of_response:1; }","duration":"802.641514ms","start":"2026-08-06T01:17:26.421902Z","end":"2026-08-06T01:17:27.224543Z","steps":["trace[972367727] 'process raft request' (duration: 801.810801ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:17:27.224619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:17:26.421885Z","time spent":"802.702135ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:17:27.224842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.793109ms","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-08-06T01:17:27.22493Z","caller":"traceutil/trace.go:171","msg":"trace[297687151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8419; }","duration":"714.947573ms","start":"2026-08-06T01:17:26.509965Z","end":"2026-08-06T01:17:27.224913Z","steps":["trace[297687151] 'agreement among raft nodes before linearized reading' (duration: 714.698807ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:17:27.224998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:17:26.509939Z","time spent":"715.018507ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-06T01:17:27.225163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.496384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-t8lb4\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-08-06T01:17:27.225191Z","caller":"traceutil/trace.go:171","msg":"trace[31538092] range","detail":"{range_begin:/registry/pods/openstack/keepalived-t8lb4; range_end:; response_count:1; response_revision:8419; }","duration":"533.556705ms","start":"2026-08-06T01:17:26.691625Z","end":"2026-08-06T01:17:27.225182Z","steps":["trace[31538092] 'agreement among raft nodes before linearized reading' (duration: 533.486653ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:17:27.225204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:17:26.691576Z","time spent":"533.625278ms","remote":"127.0.0.1:37128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-t8lb4\" "} {"level":"warn","ts":"2026-08-06T01:17:27.225212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.358493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-06T01:17:27.225272Z","caller":"traceutil/trace.go:171","msg":"trace[1086401200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8419; }","duration":"711.434445ms","start":"2026-08-06T01:17:26.513824Z","end":"2026-08-06T01:17:27.225258Z","steps":["trace[1086401200] 'agreement among raft nodes before linearized reading' (duration: 711.253439ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:17:27.22532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:17:26.513817Z","time spent":"711.495198ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-06T01:17:27.225423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.188589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-08-06T01:17:27.225374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.960153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:17:27.22554Z","caller":"traceutil/trace.go:171","msg":"trace[2003313185] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8419; }","duration":"711.336783ms","start":"2026-08-06T01:17:26.514176Z","end":"2026-08-06T01:17:27.225513Z","steps":["trace[2003313185] 'agreement among raft nodes before linearized reading' (duration: 710.994793ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:17:27.2256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:17:26.514171Z","time spent":"711.418185ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-06T01:17:27.225957Z","caller":"traceutil/trace.go:171","msg":"trace[902363347] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8419; }","duration":"275.291144ms","start":"2026-08-06T01:17:26.950282Z","end":"2026-08-06T01:17:27.225573Z","steps":["trace[902363347] 'agreement among raft nodes before linearized reading' (duration: 274.906382ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:17:28.410566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:17:28.056522Z","time spent":"354.032131ms","remote":"127.0.0.1:51096","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-06T01:17:28.410735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.509828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:17:28.410858Z","caller":"traceutil/trace.go:171","msg":"trace[819182515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8423; }","duration":"460.684194ms","start":"2026-08-06T01:17:27.950144Z","end":"2026-08-06T01:17:28.410828Z","steps":["trace[819182515] 'range keys from in-memory index tree' (duration: 460.423897ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:17:28.410927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:17:27.950119Z","time spent":"460.791927ms","remote":"127.0.0.1:36940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-06T01:17:28.411067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.778307ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T01:17:28.411236Z","caller":"traceutil/trace.go:171","msg":"trace[922295561] transaction","detail":"{read_only:false; response_revision:8424; number_of_response:1; }","duration":"297.879489ms","start":"2026-08-06T01:17:28.113326Z","end":"2026-08-06T01:17:28.411205Z","steps":["trace[922295561] 'process raft request' (duration: 195.828164ms)","trace[922295561] 'compare' (duration: 101.469388ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:17:28.472328Z","caller":"traceutil/trace.go:171","msg":"trace[176544016] transaction","detail":"{read_only:false; response_revision:8425; number_of_response:1; }","duration":"358.940358ms","start":"2026-08-06T01:17:28.113359Z","end":"2026-08-06T01:17:28.472299Z","steps":["trace[176544016] 'process raft request' (duration: 358.735862ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:17:28.472524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:17:28.113325Z","time spent":"359.092103ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:17:28.472531Z","caller":"traceutil/trace.go:171","msg":"trace[1498726863] transaction","detail":"{read_only:false; response_revision:8426; number_of_response:1; }","duration":"358.140516ms","start":"2026-08-06T01:17:28.114368Z","end":"2026-08-06T01:17:28.472508Z","steps":["trace[1498726863] 'process raft request' (duration: 357.882129ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:17:28.47265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:17:28.114344Z","time spent":"358.23868ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:17:28.652225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.328424ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T01:17:28.652498Z","caller":"traceutil/trace.go:171","msg":"trace[223655767] transaction","detail":"{read_only:false; response_revision:8427; number_of_response:1; }","duration":"173.621139ms","start":"2026-08-06T01:17:28.478844Z","end":"2026-08-06T01:17:28.652465Z","steps":["trace[223655767] 'process raft request' (duration: 66.893123ms)","trace[223655767] 'compare' (duration: 106.007615ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:17:28.652575Z","caller":"traceutil/trace.go:171","msg":"trace[963396771] transaction","detail":"{read_only:false; response_revision:8428; number_of_response:1; }","duration":"159.552383ms","start":"2026-08-06T01:17:28.492995Z","end":"2026-08-06T01:17:28.652547Z","steps":["trace[963396771] 'process raft request' (duration: 159.387768ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:17:54.688143Z","caller":"traceutil/trace.go:171","msg":"trace[784712764] transaction","detail":"{read_only:false; response_revision:8616; number_of_response:1; }","duration":"118.640631ms","start":"2026-08-06T01:17:54.569471Z","end":"2026-08-06T01:17:54.688112Z","steps":["trace[784712764] 'process raft request' (duration: 118.328292ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:17:54.693635Z","caller":"traceutil/trace.go:171","msg":"trace[526099715] transaction","detail":"{read_only:false; response_revision:8617; number_of_response:1; }","duration":"121.116481ms","start":"2026-08-06T01:17:54.57249Z","end":"2026-08-06T01:17:54.693606Z","steps":["trace[526099715] 'process raft request' (duration: 120.971856ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:17:56.986045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.908914ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T01:17:56.98613Z","caller":"traceutil/trace.go:171","msg":"trace[59256913] transaction","detail":"{read_only:false; response_revision:8630; number_of_response:1; }","duration":"285.214223ms","start":"2026-08-06T01:17:56.700902Z","end":"2026-08-06T01:17:56.986117Z","steps":["trace[59256913] 'process raft request' (duration: 109.167025ms)","trace[59256913] 'compare' (duration: 175.192883ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:17:57.141102Z","caller":"traceutil/trace.go:171","msg":"trace[901197549] linearizableReadLoop","detail":"{readStateIndex:8937; appliedIndex:8935; }","duration":"342.068423ms","start":"2026-08-06T01:17:56.798991Z","end":"2026-08-06T01:17:57.141059Z","steps":["trace[901197549] 'read index received' (duration: 11.089993ms)","trace[901197549] 'applied index is now lower than readState.Index' (duration: 330.97743ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:17:57.141151Z","caller":"traceutil/trace.go:171","msg":"trace[1263237851] transaction","detail":"{read_only:false; response_revision:8631; number_of_response:1; }","duration":"435.763511ms","start":"2026-08-06T01:17:56.705348Z","end":"2026-08-06T01:17:57.141111Z","steps":["trace[1263237851] 'process raft request' (duration: 431.130341ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:17:57.141355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:17:56.705318Z","time spent":"435.920047ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:17:57.141635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.611159ms","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-08-06T01:17:57.14181Z","caller":"traceutil/trace.go:171","msg":"trace[1541711501] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8631; }","duration":"342.844125ms","start":"2026-08-06T01:17:56.798936Z","end":"2026-08-06T01:17:57.14178Z","steps":["trace[1541711501] 'agreement among raft nodes before linearized reading' (duration: 342.364852ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:17:57.141895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:17:56.79891Z","time spent":"342.970729ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-06T01:17:59.292397Z","caller":"traceutil/trace.go:171","msg":"trace[1426559977] linearizableReadLoop","detail":"{readStateIndex:8949; appliedIndex:8948; }","duration":"137.902263ms","start":"2026-08-06T01:17:59.154463Z","end":"2026-08-06T01:17:59.292365Z","steps":["trace[1426559977] 'read index received' (duration: 126.103811ms)","trace[1426559977] 'applied index is now lower than readState.Index' (duration: 11.792622ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:17:59.292642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.15389ms","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-08-06T01:17:59.292669Z","caller":"traceutil/trace.go:171","msg":"trace[304598050] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8642; }","duration":"138.256033ms","start":"2026-08-06T01:17:59.154403Z","end":"2026-08-06T01:17:59.292659Z","steps":["trace[304598050] 'agreement among raft nodes before linearized reading' (duration: 138.064668ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:17:59.292672Z","caller":"traceutil/trace.go:171","msg":"trace[549550808] transaction","detail":"{read_only:false; response_revision:8642; number_of_response:1; }","duration":"144.979393ms","start":"2026-08-06T01:17:59.147639Z","end":"2026-08-06T01:17:59.292619Z","steps":["trace[549550808] 'process raft request' (duration: 132.910723ms)","trace[549550808] 'compare' (duration: 11.649718ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:18:09.716376Z","caller":"traceutil/trace.go:171","msg":"trace[1455323662] linearizableReadLoop","detail":"{readStateIndex:9047; appliedIndex:9046; }","duration":"205.560279ms","start":"2026-08-06T01:18:09.510787Z","end":"2026-08-06T01:18:09.716348Z","steps":["trace[1455323662] 'read index received' (duration: 166.530771ms)","trace[1455323662] 'applied index is now lower than readState.Index' (duration: 39.027608ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:18:09.716587Z","caller":"traceutil/trace.go:171","msg":"trace[317137072] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8736; }","duration":"207.700888ms","start":"2026-08-06T01:18:09.508876Z","end":"2026-08-06T01:18:09.716577Z","steps":["trace[317137072] 'process raft request' (duration: 168.455544ms)","trace[317137072] 'compare' (duration: 38.786152ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:18:09.716863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.052443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-qbqcl\" ","response":"range_response_count:1 size:4644"} {"level":"info","ts":"2026-08-06T01:18:09.716921Z","caller":"traceutil/trace.go:171","msg":"trace[618142346] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-qbqcl; range_end:; response_count:1; response_revision:8736; }","duration":"206.186736ms","start":"2026-08-06T01:18:09.510728Z","end":"2026-08-06T01:18:09.716915Z","steps":["trace[618142346] 'agreement among raft nodes before linearized reading' (duration: 205.979141ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:18:09.744861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.619746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-08-06T01:18:09.744929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.394936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-08-06T01:18:09.74493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.347927ms","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-08-06T01:18:09.744954Z","caller":"traceutil/trace.go:171","msg":"trace[767493026] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8737; }","duration":"181.78253ms","start":"2026-08-06T01:18:09.563151Z","end":"2026-08-06T01:18:09.744934Z","steps":["trace[767493026] 'agreement among raft nodes before linearized reading' (duration: 181.532982ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:18:09.744956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.490318ms","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-08-06T01:18:09.745116Z","caller":"traceutil/trace.go:171","msg":"trace[1865841538] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8737; }","duration":"204.696955ms","start":"2026-08-06T01:18:09.54039Z","end":"2026-08-06T01:18:09.745087Z","steps":["trace[1865841538] 'agreement among raft nodes before linearized reading' (duration: 204.333725ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:18:09.745329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.018946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-06T01:18:09.745365Z","caller":"traceutil/trace.go:171","msg":"trace[1016489160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8737; }","duration":"171.183622ms","start":"2026-08-06T01:18:09.574171Z","end":"2026-08-06T01:18:09.745354Z","steps":["trace[1016489160] 'agreement among raft nodes before linearized reading' (duration: 171.100139ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:18:09.745034Z","caller":"traceutil/trace.go:171","msg":"trace[1311659070] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8737; }","duration":"170.480651ms","start":"2026-08-06T01:18:09.574533Z","end":"2026-08-06T01:18:09.745014Z","steps":["trace[1311659070] 'agreement among raft nodes before linearized reading' (duration: 170.131702ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:18:09.745037Z","caller":"traceutil/trace.go:171","msg":"trace[1588731075] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8737; }","duration":"204.571331ms","start":"2026-08-06T01:18:09.540449Z","end":"2026-08-06T01:18:09.74502Z","steps":["trace[1588731075] 'agreement among raft nodes before linearized reading' (duration: 204.269723ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:18:09.745475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.222648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-t8lb4\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-08-06T01:18:09.745698Z","caller":"traceutil/trace.go:171","msg":"trace[1464053331] range","detail":"{range_begin:/registry/pods/openstack/keepalived-t8lb4; range_end:; response_count:1; response_revision:8737; }","duration":"146.444264ms","start":"2026-08-06T01:18:09.599242Z","end":"2026-08-06T01:18:09.745687Z","steps":["trace[1464053331] 'agreement among raft nodes before linearized reading' (duration: 146.151306ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:18:10.799212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.147214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:18:10.799318Z","caller":"traceutil/trace.go:171","msg":"trace[554035868] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:8745; }","duration":"104.327598ms","start":"2026-08-06T01:18:10.694969Z","end":"2026-08-06T01:18:10.799296Z","steps":["trace[554035868] 'count revisions from in-memory index tree' (duration: 104.069871ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:18:10.800266Z","caller":"traceutil/trace.go:171","msg":"trace[771488831] transaction","detail":"{read_only:false; response_revision:8746; number_of_response:1; }","duration":"104.355019ms","start":"2026-08-06T01:18:10.695891Z","end":"2026-08-06T01:18:10.800246Z","steps":["trace[771488831] 'process raft request' (duration: 80.095025ms)","trace[771488831] 'compare' (duration: 24.139311ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:18:11.213717Z","caller":"traceutil/trace.go:171","msg":"trace[454968277] transaction","detail":"{read_only:false; response_revision:8748; number_of_response:1; }","duration":"193.019256ms","start":"2026-08-06T01:18:11.020674Z","end":"2026-08-06T01:18:11.213693Z","steps":["trace[454968277] 'process raft request' (duration: 192.847441ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:18:11.247243Z","caller":"traceutil/trace.go:171","msg":"trace[17503667] transaction","detail":"{read_only:false; response_revision:8749; number_of_response:1; }","duration":"175.241905ms","start":"2026-08-06T01:18:11.071955Z","end":"2026-08-06T01:18:11.247197Z","steps":["trace[17503667] 'process raft request' (duration: 174.979927ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:18:11.247439Z","caller":"traceutil/trace.go:171","msg":"trace[849678867] transaction","detail":"{read_only:false; response_revision:8750; number_of_response:1; }","duration":"170.214664ms","start":"2026-08-06T01:18:11.077183Z","end":"2026-08-06T01:18:11.247398Z","steps":["trace[849678867] 'process raft request' (duration: 169.956256ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:18:12.237012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.630808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/openstack/ceph\" ","response":"range_response_count:1 size:3173"} {"level":"info","ts":"2026-08-06T01:18:12.237135Z","caller":"traceutil/trace.go:171","msg":"trace[37776047] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/openstack/ceph; range_end:; response_count:1; response_revision:8760; }","duration":"109.825643ms","start":"2026-08-06T01:18:12.12728Z","end":"2026-08-06T01:18:12.237105Z","steps":["trace[37776047] 'range keys from in-memory index tree' (duration: 109.414751ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:18:23.429919Z","caller":"traceutil/trace.go:171","msg":"trace[1625973012] transaction","detail":"{read_only:false; response_revision:8850; number_of_response:1; }","duration":"125.555146ms","start":"2026-08-06T01:18:23.304339Z","end":"2026-08-06T01:18:23.429894Z","steps":["trace[1625973012] 'process raft request' (duration: 125.317419ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:18:40.791303Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024a3.wal"} {"level":"info","ts":"2026-08-06T01:19:26.46552Z","caller":"traceutil/trace.go:171","msg":"trace[1673811367] linearizableReadLoop","detail":"{readStateIndex:9761; appliedIndex:9760; }","duration":"159.434635ms","start":"2026-08-06T01:19:26.306051Z","end":"2026-08-06T01:19:26.465486Z","steps":["trace[1673811367] 'read index received' (duration: 159.123166ms)","trace[1673811367] 'applied index is now lower than readState.Index' (duration: 310.339µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:19:26.466326Z","caller":"traceutil/trace.go:171","msg":"trace[1473029342] transaction","detail":"{read_only:false; response_revision:9430; number_of_response:1; }","duration":"194.075689ms","start":"2026-08-06T01:19:26.272218Z","end":"2026-08-06T01:19:26.466293Z","steps":["trace[1473029342] 'process raft request' (duration: 193.041ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:19:26.466867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.803488ms","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-08-06T01:19:26.466949Z","caller":"traceutil/trace.go:171","msg":"trace[1262989714] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9430; }","duration":"152.930022ms","start":"2026-08-06T01:19:26.313985Z","end":"2026-08-06T01:19:26.466915Z","steps":["trace[1262989714] 'agreement among raft nodes before linearized reading' (duration: 152.713276ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:19:26.467235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.187435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-06T01:19:26.467273Z","caller":"traceutil/trace.go:171","msg":"trace[1014073847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9430; }","duration":"161.280736ms","start":"2026-08-06T01:19:26.305983Z","end":"2026-08-06T01:19:26.467264Z","steps":["trace[1014073847] 'agreement among raft nodes before linearized reading' (duration: 161.158093ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:19:26.467654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.248171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-06T01:19:26.467697Z","caller":"traceutil/trace.go:171","msg":"trace[358196009] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9430; }","duration":"153.323633ms","start":"2026-08-06T01:19:26.314363Z","end":"2026-08-06T01:19:26.467686Z","steps":["trace[358196009] 'agreement among raft nodes before linearized reading' (duration: 153.230781ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:19:26.646318Z","caller":"traceutil/trace.go:171","msg":"trace[1142740108] linearizableReadLoop","detail":"{readStateIndex:9763; appliedIndex:9761; }","duration":"171.935076ms","start":"2026-08-06T01:19:26.47435Z","end":"2026-08-06T01:19:26.646285Z","steps":["trace[1142740108] 'read index received' (duration: 104.220421ms)","trace[1142740108] 'applied index is now lower than readState.Index' (duration: 67.709775ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:19:26.647032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.648216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:29 size:261386"} {"level":"info","ts":"2026-08-06T01:19:26.647104Z","caller":"traceutil/trace.go:171","msg":"trace[2119998985] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:29; response_revision:9433; }","duration":"172.75484ms","start":"2026-08-06T01:19:26.474333Z","end":"2026-08-06T01:19:26.647088Z","steps":["trace[2119998985] 'agreement among raft nodes before linearized reading' (duration: 172.092491ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:19:26.647432Z","caller":"traceutil/trace.go:171","msg":"trace[573806678] transaction","detail":"{read_only:false; response_revision:9431; number_of_response:1; }","duration":"174.944382ms","start":"2026-08-06T01:19:26.472462Z","end":"2026-08-06T01:19:26.647406Z","steps":["trace[573806678] 'process raft request' (duration: 106.072104ms)","trace[573806678] 'compare' (duration: 67.312853ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:19:26.64817Z","caller":"traceutil/trace.go:171","msg":"trace[979435476] transaction","detail":"{read_only:false; response_revision:9432; number_of_response:1; }","duration":"175.294261ms","start":"2026-08-06T01:19:26.472863Z","end":"2026-08-06T01:19:26.648157Z","steps":["trace[979435476] 'process raft request' (duration: 173.297524ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:19:26.648554Z","caller":"traceutil/trace.go:171","msg":"trace[1071560491] transaction","detail":"{read_only:false; response_revision:9433; number_of_response:1; }","duration":"174.033835ms","start":"2026-08-06T01:19:26.474503Z","end":"2026-08-06T01:19:26.648537Z","steps":["trace[1071560491] 'process raft request' (duration: 171.724171ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:19:49.779932Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7317567213c7319d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-06T01:19:49.786393Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-06T01:19:49.786568Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-06T01:21:47.172647Z","caller":"traceutil/trace.go:171","msg":"trace[2059327187] linearizableReadLoop","detail":"{readStateIndex:10954; appliedIndex:10953; }","duration":"161.788686ms","start":"2026-08-06T01:21:47.010821Z","end":"2026-08-06T01:21:47.17261Z","steps":["trace[2059327187] 'read index received' (duration: 80.77507ms)","trace[2059327187] 'applied index is now lower than readState.Index' (duration: 81.012536ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:21:47.17268Z","caller":"traceutil/trace.go:171","msg":"trace[880459300] transaction","detail":"{read_only:false; response_revision:10586; number_of_response:1; }","duration":"168.759752ms","start":"2026-08-06T01:21:47.003867Z","end":"2026-08-06T01:21:47.172627Z","steps":["trace[880459300] 'process raft request' (duration: 87.819219ms)","trace[880459300] 'compare' (duration: 80.791009ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:21:47.172851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.991192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/staffeln-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:21:47.172879Z","caller":"traceutil/trace.go:171","msg":"trace[950028119] range","detail":"{range_begin:/registry/jobs/openstack/staffeln-db-sync; range_end:; response_count:0; response_revision:10586; }","duration":"162.067114ms","start":"2026-08-06T01:21:47.010802Z","end":"2026-08-06T01:21:47.172869Z","steps":["trace[950028119] 'agreement among raft nodes before linearized reading' (duration: 161.978031ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:21:55.142567Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8076} {"level":"info","ts":"2026-08-06T01:21:55.207854Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8076,"took":"62.647599ms","hash":2182176569,"current-db-size-bytes":57683968,"current-db-size":"58 MB","current-db-size-in-use-bytes":29396992,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-06T01:21:55.207926Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2182176569,"revision":8076,"compact-revision":6008} {"level":"info","ts":"2026-08-06T01:26:55.15025Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10657} {"level":"info","ts":"2026-08-06T01:26:55.233707Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10657,"took":"80.579497ms","hash":2744901982,"current-db-size-bytes":57683968,"current-db-size":"58 MB","current-db-size-in-use-bytes":27467776,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-06T01:26:55.233774Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2744901982,"revision":10657,"compact-revision":8076} {"level":"info","ts":"2026-08-06T01:29:44.398486Z","caller":"traceutil/trace.go:171","msg":"trace[1907460719] linearizableReadLoop","detail":"{readStateIndex:14492; appliedIndex:14491; }","duration":"168.871399ms","start":"2026-08-06T01:29:44.229589Z","end":"2026-08-06T01:29:44.39846Z","steps":["trace[1907460719] 'read index received' (duration: 168.763296ms)","trace[1907460719] 'applied index is now lower than readState.Index' (duration: 107.043µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:29:44.398624Z","caller":"traceutil/trace.go:171","msg":"trace[121232922] transaction","detail":"{read_only:false; response_revision:14009; number_of_response:1; }","duration":"247.236579ms","start":"2026-08-06T01:29:44.151349Z","end":"2026-08-06T01:29:44.398586Z","steps":["trace[121232922] 'process raft request' (duration: 246.93178ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:29:44.398771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.163188ms","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-08-06T01:29:44.398823Z","caller":"traceutil/trace.go:171","msg":"trace[562475350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14009; }","duration":"169.28651ms","start":"2026-08-06T01:29:44.229522Z","end":"2026-08-06T01:29:44.398809Z","steps":["trace[562475350] 'agreement among raft nodes before linearized reading' (duration: 169.097636ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:29:44.673712Z","caller":"traceutil/trace.go:171","msg":"trace[1172510943] linearizableReadLoop","detail":"{readStateIndex:14495; appliedIndex:14492; }","duration":"275.081895ms","start":"2026-08-06T01:29:44.398595Z","end":"2026-08-06T01:29:44.673677Z","steps":["trace[1172510943] 'read index received' (duration: 274.825877ms)","trace[1172510943] 'applied index is now lower than readState.Index' (duration: 255.008µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:29:44.673912Z","caller":"traceutil/trace.go:171","msg":"trace[1322641925] transaction","detail":"{read_only:false; response_revision:14010; number_of_response:1; }","duration":"395.181066ms","start":"2026-08-06T01:29:44.278693Z","end":"2026-08-06T01:29:44.673874Z","steps":["trace[1322641925] 'process raft request' (duration: 394.717953ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:29:44.674Z","caller":"traceutil/trace.go:171","msg":"trace[384072862] transaction","detail":"{read_only:false; response_revision:14011; number_of_response:1; }","duration":"385.26906ms","start":"2026-08-06T01:29:44.288718Z","end":"2026-08-06T01:29:44.673987Z","steps":["trace[384072862] 'process raft request' (duration: 384.870329ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:29:44.674069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.481478ms","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-08-06T01:29:44.673907Z","caller":"traceutil/trace.go:171","msg":"trace[30747860] transaction","detail":"{read_only:false; response_revision:14012; number_of_response:1; }","duration":"280.794092ms","start":"2026-08-06T01:29:44.393065Z","end":"2026-08-06T01:29:44.67386Z","steps":["trace[30747860] 'process raft request' (duration: 280.573206ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:29:44.674104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:29:44.278666Z","time spent":"395.33283ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:29:44.674141Z","caller":"traceutil/trace.go:171","msg":"trace[929725835] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14012; }","duration":"439.612492ms","start":"2026-08-06T01:29:44.234511Z","end":"2026-08-06T01:29:44.674124Z","steps":["trace[929725835] 'agreement among raft nodes before linearized reading' (duration: 439.390466ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:29:44.674187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:29:44.234492Z","time spent":"439.685413ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-06T01:29:44.674197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:29:44.288711Z","time spent":"385.352542ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:29:44.674538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.334562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-08-06T01:29:44.675019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.338655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:29:44.675087Z","caller":"traceutil/trace.go:171","msg":"trace[1638951902] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:14013; }","duration":"126.462329ms","start":"2026-08-06T01:29:44.548605Z","end":"2026-08-06T01:29:44.675067Z","steps":["trace[1638951902] 'agreement among raft nodes before linearized reading' (duration: 126.341696ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:29:44.67464Z","caller":"traceutil/trace.go:171","msg":"trace[702199020] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14012; }","duration":"334.475876ms","start":"2026-08-06T01:29:44.340139Z","end":"2026-08-06T01:29:44.674615Z","steps":["trace[702199020] 'agreement among raft nodes before linearized reading' (duration: 333.695725ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:29:44.675327Z","caller":"traceutil/trace.go:171","msg":"trace[1657653231] transaction","detail":"{read_only:false; response_revision:14013; number_of_response:1; }","duration":"271.794313ms","start":"2026-08-06T01:29:44.403509Z","end":"2026-08-06T01:29:44.675303Z","steps":["trace[1657653231] 'process raft request' (duration: 271.373141ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:29:44.67539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:29:44.340131Z","time spent":"335.147646ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-06T01:29:45.750171Z","caller":"traceutil/trace.go:171","msg":"trace[771106937] transaction","detail":"{read_only:false; response_revision:14021; number_of_response:1; }","duration":"145.030945ms","start":"2026-08-06T01:29:45.605105Z","end":"2026-08-06T01:29:45.750136Z","steps":["trace[771106937] 'process raft request' (duration: 107.127841ms)","trace[771106937] 'compare' (duration: 37.767981ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:30:08.171183Z","caller":"traceutil/trace.go:171","msg":"trace[364238720] transaction","detail":"{read_only:false; response_revision:14321; number_of_response:1; }","duration":"401.015737ms","start":"2026-08-06T01:30:07.770136Z","end":"2026-08-06T01:30:08.171151Z","steps":["trace[364238720] 'process raft request' (duration: 400.762391ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:30:08.171372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:30:07.770102Z","time spent":"401.169783ms","remote":"127.0.0.1:37128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3738,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:30:08.188696Z","caller":"traceutil/trace.go:171","msg":"trace[791002840] linearizableReadLoop","detail":"{readStateIndex:14819; appliedIndex:14817; }","duration":"324.998263ms","start":"2026-08-06T01:30:07.86367Z","end":"2026-08-06T01:30:08.188668Z","steps":["trace[791002840] 'read index received' (duration: 307.334642ms)","trace[791002840] 'applied index is now lower than readState.Index' (duration: 17.662051ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:30:08.188785Z","caller":"traceutil/trace.go:171","msg":"trace[1826236972] transaction","detail":"{read_only:false; response_revision:14322; number_of_response:1; }","duration":"339.143987ms","start":"2026-08-06T01:30:07.849613Z","end":"2026-08-06T01:30:08.188757Z","steps":["trace[1826236972] 'process raft request' (duration: 338.887439ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:30:08.188946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.267181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-08-06T01:30:08.189013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:30:07.849579Z","time spent":"339.303692ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:30:08.189056Z","caller":"traceutil/trace.go:171","msg":"trace[66502042] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14322; }","duration":"325.384744ms","start":"2026-08-06T01:30:07.86365Z","end":"2026-08-06T01:30:08.189035Z","steps":["trace[66502042] 'agreement among raft nodes before linearized reading' (duration: 325.129757ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:30:08.18912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:30:07.863576Z","time spent":"325.534478ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-06T01:30:08.189024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.551254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T01:30:08.189175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.764431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T01:30:08.189232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.899438ms","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-08-06T01:30:08.189254Z","caller":"traceutil/trace.go:171","msg":"trace[1203463541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14322; }","duration":"187.9551ms","start":"2026-08-06T01:30:08.001292Z","end":"2026-08-06T01:30:08.189247Z","steps":["trace[1203463541] 'agreement among raft nodes before linearized reading' (duration: 187.845567ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:30:08.189253Z","caller":"traceutil/trace.go:171","msg":"trace[1809166800] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:14322; }","duration":"110.893336ms","start":"2026-08-06T01:30:08.078342Z","end":"2026-08-06T01:30:08.189236Z","steps":["trace[1809166800] 'agreement among raft nodes before linearized reading' (duration: 110.754342ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:30:08.189269Z","caller":"traceutil/trace.go:171","msg":"trace[1882039900] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14322; }","duration":"240.808531ms","start":"2026-08-06T01:30:07.948399Z","end":"2026-08-06T01:30:08.189208Z","steps":["trace[1882039900] 'agreement among raft nodes before linearized reading' (duration: 240.533363ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:31:14.930296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.529529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-kzf88\" ","response":"range_response_count:1 size:9798"} {"level":"info","ts":"2026-08-06T01:31:14.930385Z","caller":"traceutil/trace.go:171","msg":"trace[1428414679] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-kzf88; range_end:; response_count:1; response_revision:15029; }","duration":"108.675394ms","start":"2026-08-06T01:31:14.82169Z","end":"2026-08-06T01:31:14.930365Z","steps":["trace[1428414679] 'range keys from in-memory index tree' (duration: 108.299603ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:31:50.107874Z","caller":"traceutil/trace.go:171","msg":"trace[1092733821] transaction","detail":"{read_only:false; response_revision:15215; number_of_response:1; }","duration":"120.078912ms","start":"2026-08-06T01:31:49.987771Z","end":"2026-08-06T01:31:50.10785Z","steps":["trace[1092733821] 'process raft request' (duration: 119.938447ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:31:55.158649Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12830} {"level":"info","ts":"2026-08-06T01:31:55.240845Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12830,"took":"77.892217ms","hash":2056354335,"current-db-size-bytes":57683968,"current-db-size":"58 MB","current-db-size-in-use-bytes":30285824,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-06T01:31:55.240984Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2056354335,"revision":12830,"compact-revision":10657} {"level":"info","ts":"2026-08-06T01:36:55.165414Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15239} {"level":"info","ts":"2026-08-06T01:36:55.265225Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15239,"took":"95.292787ms","hash":2836224238,"current-db-size-bytes":57683968,"current-db-size":"58 MB","current-db-size-in-use-bytes":32010240,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-06T01:36:55.265297Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2836224238,"revision":15239,"compact-revision":12830} {"level":"info","ts":"2026-08-06T01:37:22.162921Z","caller":"traceutil/trace.go:171","msg":"trace[736539728] linearizableReadLoop","detail":"{readStateIndex:18316; appliedIndex:18315; }","duration":"190.612215ms","start":"2026-08-06T01:37:21.972279Z","end":"2026-08-06T01:37:22.162891Z","steps":["trace[736539728] 'read index received' (duration: 190.337167ms)","trace[736539728] 'applied index is now lower than readState.Index' (duration: 270.137µs)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:37:22.163208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.899702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-06T01:37:22.163254Z","caller":"traceutil/trace.go:171","msg":"trace[1969433477] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17709; }","duration":"190.986696ms","start":"2026-08-06T01:37:21.972254Z","end":"2026-08-06T01:37:22.163241Z","steps":["trace[1969433477] 'agreement among raft nodes before linearized reading' (duration: 190.763069ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:37:22.16458Z","caller":"traceutil/trace.go:171","msg":"trace[1036319265] transaction","detail":"{read_only:false; response_revision:17709; number_of_response:1; }","duration":"195.511862ms","start":"2026-08-06T01:37:21.969032Z","end":"2026-08-06T01:37:22.164544Z","steps":["trace[1036319265] 'process raft request' (duration: 193.667551ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:37:28.760102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.35131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T01:37:28.760201Z","caller":"traceutil/trace.go:171","msg":"trace[1000247211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17743; }","duration":"746.514334ms","start":"2026-08-06T01:37:28.013664Z","end":"2026-08-06T01:37:28.760179Z","steps":["trace[1000247211] 'range keys from in-memory index tree' (duration: 746.088852ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:37:28.760236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:37:28.013632Z","time spent":"746.594676ms","remote":"127.0.0.1:36940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-06T01:37:28.760296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.06089396s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-kzf88\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-08-06T01:37:28.760403Z","caller":"traceutil/trace.go:171","msg":"trace[1708806794] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-kzf88; range_end:; response_count:1; response_revision:17743; }","duration":"1.061026223s","start":"2026-08-06T01:37:27.699353Z","end":"2026-08-06T01:37:28.76038Z","steps":["trace[1708806794] 'range keys from in-memory index tree' (duration: 1.060673224s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:37:28.760465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:37:27.699272Z","time spent":"1.061174887s","remote":"127.0.0.1:37128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9822,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-kzf88\" "} {"level":"warn","ts":"2026-08-06T01:37:28.761126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:37:28.198156Z","time spent":"562.960176ms","remote":"127.0.0.1:41642","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-06T01:37:28.761889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.420438ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-06T01:37:28.761955Z","caller":"traceutil/trace.go:171","msg":"trace[871055005] transaction","detail":"{read_only:false; response_revision:17744; number_of_response:1; }","duration":"577.487401ms","start":"2026-08-06T01:37:28.184457Z","end":"2026-08-06T01:37:28.761944Z","steps":["trace[871055005] 'process raft request' (duration: 291.95361ms)","trace[871055005] 'compare' (duration: 285.106749ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:37:28.761992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:37:28.184424Z","time spent":"577.549424ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:37:28.780805Z","caller":"traceutil/trace.go:171","msg":"trace[939898954] linearizableReadLoop","detail":"{readStateIndex:18353; appliedIndex:18351; }","duration":"534.359489ms","start":"2026-08-06T01:37:28.24641Z","end":"2026-08-06T01:37:28.780769Z","steps":["trace[939898954] 'read index received' (duration: 230.045774ms)","trace[939898954] 'applied index is now lower than readState.Index' (duration: 304.312375ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:37:28.78122Z","caller":"traceutil/trace.go:171","msg":"trace[1537725952] transaction","detail":"{read_only:false; response_revision:17745; number_of_response:1; }","duration":"535.531602ms","start":"2026-08-06T01:37:28.245673Z","end":"2026-08-06T01:37:28.781205Z","steps":["trace[1537725952] 'process raft request' (duration: 534.957596ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:37:28.781609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:37:28.245639Z","time spent":"535.795249ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:37:28.78187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.467559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-06T01:37:28.781909Z","caller":"traceutil/trace.go:171","msg":"trace[1540859180] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17745; }","duration":"535.535521ms","start":"2026-08-06T01:37:28.246362Z","end":"2026-08-06T01:37:28.781898Z","steps":["trace[1540859180] 'agreement among raft nodes before linearized reading' (duration: 535.408538ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:37:28.781939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:37:28.246349Z","time spent":"535.583474ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-06T01:37:28.830874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.563664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T01:37:28.830974Z","caller":"traceutil/trace.go:171","msg":"trace[365448399] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:17746; }","duration":"572.697348ms","start":"2026-08-06T01:37:28.258253Z","end":"2026-08-06T01:37:28.83095Z","steps":["trace[365448399] 'agreement among raft nodes before linearized reading' (duration: 572.494133ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:37:28.831017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:37:28.258195Z","time spent":"572.810191ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"info","ts":"2026-08-06T01:37:28.831353Z","caller":"traceutil/trace.go:171","msg":"trace[1245791084] transaction","detail":"{read_only:false; response_revision:17746; number_of_response:1; }","duration":"285.006576ms","start":"2026-08-06T01:37:28.546335Z","end":"2026-08-06T01:37:28.831342Z","steps":["trace[1245791084] 'process raft request' (duration: 284.199244ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:37:28.832452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.592989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-06T01:37:28.832481Z","caller":"traceutil/trace.go:171","msg":"trace[1229271214] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17746; }","duration":"150.672131ms","start":"2026-08-06T01:37:28.681802Z","end":"2026-08-06T01:37:28.832474Z","steps":["trace[1229271214] 'agreement among raft nodes before linearized reading' (duration: 150.599038ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:37:28.832599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.728533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-06T01:37:28.832622Z","caller":"traceutil/trace.go:171","msg":"trace[1456059413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17746; }","duration":"211.792484ms","start":"2026-08-06T01:37:28.620823Z","end":"2026-08-06T01:37:28.832616Z","steps":["trace[1456059413] 'agreement among raft nodes before linearized reading' (duration: 211.731003ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:37:28.832736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.650111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.48\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-08-06T01:37:28.832757Z","caller":"traceutil/trace.go:171","msg":"trace[1826659524] range","detail":"{range_begin:/registry/masterleases/199.19.213.48; range_end:; response_count:1; response_revision:17746; }","duration":"324.718134ms","start":"2026-08-06T01:37:28.508034Z","end":"2026-08-06T01:37:28.832752Z","steps":["trace[1826659524] 'agreement among raft nodes before linearized reading' (duration: 324.662613ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:37:28.832776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:37:28.508005Z","time spent":"324.766375ms","remote":"127.0.0.1:36972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":160,"request content":"key:\"/registry/masterleases/199.19.213.48\" "} {"level":"info","ts":"2026-08-06T01:37:28.946871Z","caller":"traceutil/trace.go:171","msg":"trace[1993800057] transaction","detail":"{read_only:false; response_revision:17747; number_of_response:1; }","duration":"153.601262ms","start":"2026-08-06T01:37:28.79324Z","end":"2026-08-06T01:37:28.946842Z","steps":["trace[1993800057] 'process raft request' (duration: 153.442958ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:37:28.94862Z","caller":"traceutil/trace.go:171","msg":"trace[1967154145] transaction","detail":"{read_only:false; response_revision:17749; number_of_response:1; }","duration":"110.024798ms","start":"2026-08-06T01:37:28.838565Z","end":"2026-08-06T01:37:28.94859Z","steps":["trace[1967154145] 'process raft request' (duration: 109.973147ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:37:28.948842Z","caller":"traceutil/trace.go:171","msg":"trace[455101882] transaction","detail":"{read_only:false; response_revision:17748; number_of_response:1; }","duration":"110.383338ms","start":"2026-08-06T01:37:28.838422Z","end":"2026-08-06T01:37:28.948806Z","steps":["trace[455101882] 'process raft request' (duration: 109.967776ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:37:33.665433Z","caller":"traceutil/trace.go:171","msg":"trace[684312198] transaction","detail":"{read_only:false; response_revision:17787; number_of_response:1; }","duration":"135.338094ms","start":"2026-08-06T01:37:33.530066Z","end":"2026-08-06T01:37:33.665404Z","steps":["trace[684312198] 'process raft request' (duration: 134.765719ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:37:33.66595Z","caller":"traceutil/trace.go:171","msg":"trace[1147029549] transaction","detail":"{read_only:false; response_revision:17788; number_of_response:1; }","duration":"135.676253ms","start":"2026-08-06T01:37:33.530253Z","end":"2026-08-06T01:37:33.665929Z","steps":["trace[1147029549] 'process raft request' (duration: 135.433787ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:37:41.950647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.735502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fae97555-8adb-4a85-a5ac-2651a38521a9\" ","response":"range_response_count:1 size:3639"} {"level":"info","ts":"2026-08-06T01:37:41.950758Z","caller":"traceutil/trace.go:171","msg":"trace[1261280736] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fae97555-8adb-4a85-a5ac-2651a38521a9; range_end:; response_count:1; response_revision:17940; }","duration":"127.895745ms","start":"2026-08-06T01:37:41.822827Z","end":"2026-08-06T01:37:41.950722Z","steps":["trace[1261280736] 'range keys from in-memory index tree' (duration: 127.479345ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:40:39.570633Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7317567213c7319d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-06T01:40:39.575727Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-06T01:40:39.575871Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-06T01:41:55.171952Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17532} {"level":"info","ts":"2026-08-06T01:41:55.272293Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17532,"took":"96.747702ms","hash":1896325389,"current-db-size-bytes":57683968,"current-db-size":"58 MB","current-db-size-in-use-bytes":34476032,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-06T01:41:55.272379Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1896325389,"revision":17532,"compact-revision":15239} {"level":"info","ts":"2026-08-06T01:42:40.80923Z","caller":"traceutil/trace.go:171","msg":"trace[187070908] transaction","detail":"{read_only:false; response_revision:20252; number_of_response:1; }","duration":"106.513612ms","start":"2026-08-06T01:42:40.702678Z","end":"2026-08-06T01:42:40.809191Z","steps":["trace[187070908] 'process raft request' (duration: 106.43796ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:42:40.809388Z","caller":"traceutil/trace.go:171","msg":"trace[1499077486] transaction","detail":"{read_only:false; response_revision:20251; number_of_response:1; }","duration":"107.085297ms","start":"2026-08-06T01:42:40.702261Z","end":"2026-08-06T01:42:40.809346Z","steps":["trace[1499077486] 'process raft request' (duration: 106.634354ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:42:46.245275Z","caller":"traceutil/trace.go:171","msg":"trace[1424278353] transaction","detail":"{read_only:false; response_revision:20278; number_of_response:1; }","duration":"154.973931ms","start":"2026-08-06T01:42:46.090265Z","end":"2026-08-06T01:42:46.245239Z","steps":["trace[1424278353] 'process raft request' (duration: 139.643727ms)","trace[1424278353] 'compare' (duration: 14.566383ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:42:46.542425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.264385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-06T01:42:46.542547Z","caller":"traceutil/trace.go:171","msg":"trace[1896975073] linearizableReadLoop","detail":"{readStateIndex:20970; appliedIndex:20968; }","duration":"314.26418ms","start":"2026-08-06T01:42:46.228264Z","end":"2026-08-06T01:42:46.542528Z","steps":["trace[1896975073] 'read index received' (duration: 1.657286ms)","trace[1896975073] 'applied index is now lower than readState.Index' (duration: 312.605374ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:42:46.542604Z","caller":"traceutil/trace.go:171","msg":"trace[1285617527] transaction","detail":"{read_only:false; response_revision:20279; number_of_response:1; }","duration":"422.184431ms","start":"2026-08-06T01:42:46.120364Z","end":"2026-08-06T01:42:46.542548Z","steps":["trace[1285617527] 'process raft request' (duration: 227.691669ms)","trace[1285617527] 'compare' (duration: 194.05405ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:42:46.542724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.465235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-06T01:42:46.54277Z","caller":"traceutil/trace.go:171","msg":"trace[757301090] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:20279; }","duration":"314.551878ms","start":"2026-08-06T01:42:46.228201Z","end":"2026-08-06T01:42:46.542753Z","steps":["trace[757301090] 'agreement among raft nodes before linearized reading' (duration: 314.382503ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:42:46.542814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:42:46.228175Z","time spent":"314.629479ms","remote":"127.0.0.1:37290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":91,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-08-06T01:42:46.542813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:42:46.120331Z","time spent":"422.372886ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:43:02.497138Z","caller":"traceutil/trace.go:171","msg":"trace[157022157] transaction","detail":"{read_only:false; response_revision:20362; number_of_response:1; }","duration":"178.86946ms","start":"2026-08-06T01:43:02.318242Z","end":"2026-08-06T01:43:02.497112Z","steps":["trace[157022157] 'process raft request' (duration: 178.650814ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:43:02.958825Z","caller":"traceutil/trace.go:171","msg":"trace[1806425532] transaction","detail":"{read_only:false; response_revision:20363; number_of_response:1; }","duration":"355.670303ms","start":"2026-08-06T01:43:02.603083Z","end":"2026-08-06T01:43:02.958753Z","steps":["trace[1806425532] 'process raft request' (duration: 257.125101ms)","trace[1806425532] 'compare' (duration: 98.351316ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:43:02.959155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:43:02.603056Z","time spent":"355.89978ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:43:03.190614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.417442ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-06T01:43:03.190771Z","caller":"traceutil/trace.go:171","msg":"trace[448458230] transaction","detail":"{read_only:false; response_revision:20364; number_of_response:1; }","duration":"203.970914ms","start":"2026-08-06T01:43:02.986775Z","end":"2026-08-06T01:43:03.190746Z","steps":["trace[448458230] 'process raft request' (duration: 77.305056ms)","trace[448458230] 'compare' (duration: 126.252157ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:43:03.496809Z","caller":"traceutil/trace.go:171","msg":"trace[538829705] transaction","detail":"{read_only:false; response_revision:20365; number_of_response:1; }","duration":"509.092351ms","start":"2026-08-06T01:43:02.987652Z","end":"2026-08-06T01:43:03.496744Z","steps":["trace[538829705] 'process raft request' (duration: 508.841663ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:43:03.497053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:43:02.987644Z","time spent":"509.278545ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:43:03.497352Z","caller":"traceutil/trace.go:171","msg":"trace[128639131] linearizableReadLoop","detail":"{readStateIndex:21061; appliedIndex:21058; }","duration":"483.519784ms","start":"2026-08-06T01:43:03.013782Z","end":"2026-08-06T01:43:03.497302Z","steps":["trace[128639131] 'read index received' (duration: 50.212176ms)","trace[128639131] 'applied index is now lower than readState.Index' (duration: 433.305758ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:43:03.497491Z","caller":"traceutil/trace.go:171","msg":"trace[93734930] transaction","detail":"{read_only:false; response_revision:20366; number_of_response:1; }","duration":"508.36982ms","start":"2026-08-06T01:43:02.989055Z","end":"2026-08-06T01:43:03.497425Z","steps":["trace[93734930] 'process raft request' (duration: 507.64003ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:43:03.497581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.754521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T01:43:03.497626Z","caller":"traceutil/trace.go:171","msg":"trace[879615641] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:20366; }","duration":"483.843274ms","start":"2026-08-06T01:43:03.013771Z","end":"2026-08-06T01:43:03.497614Z","steps":["trace[879615641] 'agreement among raft nodes before linearized reading' (duration: 483.687209ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:43:03.497655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:43:03.013725Z","time spent":"483.922164ms","remote":"127.0.0.1:35004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-08-06T01:43:03.497725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.532677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-08-06T01:43:03.497734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:43:02.989036Z","time spent":"508.567387ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:43:03.497778Z","caller":"traceutil/trace.go:171","msg":"trace[832409241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20366; }","duration":"222.656389ms","start":"2026-08-06T01:43:03.275108Z","end":"2026-08-06T01:43:03.497764Z","steps":["trace[832409241] 'agreement among raft nodes before linearized reading' (duration: 222.434852ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:43:03.765973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.558926ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-06T01:43:03.766129Z","caller":"traceutil/trace.go:171","msg":"trace[198914894] linearizableReadLoop","detail":"{readStateIndex:21062; appliedIndex:21061; }","duration":"174.343965ms","start":"2026-08-06T01:43:03.59176Z","end":"2026-08-06T01:43:03.766104Z","steps":["trace[198914894] 'read index received' (duration: 61.552µs)","trace[198914894] 'applied index is now lower than readState.Index' (duration: 174.279583ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:43:03.766196Z","caller":"traceutil/trace.go:171","msg":"trace[1890558334] transaction","detail":"{read_only:false; response_revision:20367; number_of_response:1; }","duration":"262.458339ms","start":"2026-08-06T01:43:03.503688Z","end":"2026-08-06T01:43:03.766147Z","steps":["trace[1890558334] 'process raft request' (duration: 81.638985ms)","trace[1890558334] 'compare' (duration: 180.107345ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:43:03.766387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.621652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-06T01:43:03.766543Z","caller":"traceutil/trace.go:171","msg":"trace[1771746710] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20367; }","duration":"174.822689ms","start":"2026-08-06T01:43:03.591698Z","end":"2026-08-06T01:43:03.766521Z","steps":["trace[1771746710] 'agreement among raft nodes before linearized reading' (duration: 174.47583ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:43:03.983767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.858046ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-06T01:43:03.983989Z","caller":"traceutil/trace.go:171","msg":"trace[277348325] linearizableReadLoop","detail":"{readStateIndex:21063; appliedIndex:21062; }","duration":"217.779904ms","start":"2026-08-06T01:43:03.766179Z","end":"2026-08-06T01:43:03.983959Z","steps":["trace[277348325] 'read index received' (duration: 11.705832ms)","trace[277348325] 'applied index is now lower than readState.Index' (duration: 206.071652ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:43:03.984046Z","caller":"traceutil/trace.go:171","msg":"trace[1352078226] transaction","detail":"{read_only:false; response_revision:20368; number_of_response:1; }","duration":"212.745936ms","start":"2026-08-06T01:43:03.771283Z","end":"2026-08-06T01:43:03.984029Z","steps":["trace[1352078226] 'process raft request' (duration: 212.587102ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:43:03.984256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.431109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-06T01:43:03.984367Z","caller":"traceutil/trace.go:171","msg":"trace[1846877096] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20368; }","duration":"392.608863ms","start":"2026-08-06T01:43:03.591729Z","end":"2026-08-06T01:43:03.984337Z","steps":["trace[1846877096] 'agreement among raft nodes before linearized reading' (duration: 392.302395ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:43:03.984415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:43:03.591698Z","time spent":"392.706016ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-06T01:46:55.179576Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19996} {"level":"info","ts":"2026-08-06T01:46:55.294713Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19996,"took":"110.115838ms","hash":4184490,"current-db-size-bytes":57683968,"current-db-size":"58 MB","current-db-size-in-use-bytes":41172992,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-08-06T01:46:55.294788Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4184490,"revision":19996,"compact-revision":17532} {"level":"info","ts":"2026-08-06T01:46:57.565199Z","caller":"traceutil/trace.go:171","msg":"trace[780305725] transaction","detail":"{read_only:false; response_revision:22811; number_of_response:1; }","duration":"204.59157ms","start":"2026-08-06T01:46:57.360578Z","end":"2026-08-06T01:46:57.56517Z","steps":["trace[780305725] 'process raft request' (duration: 204.350203ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:46:58.071802Z","caller":"traceutil/trace.go:171","msg":"trace[949857888] linearizableReadLoop","detail":"{readStateIndex:23585; appliedIndex:23584; }","duration":"316.863786ms","start":"2026-08-06T01:46:57.754904Z","end":"2026-08-06T01:46:58.071768Z","steps":["trace[949857888] 'read index received' (duration: 316.755083ms)","trace[949857888] 'applied index is now lower than readState.Index' (duration: 107.513µs)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:46:58.072047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.118063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T01:46:58.072097Z","caller":"traceutil/trace.go:171","msg":"trace[1218570452] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:22815; }","duration":"317.255408ms","start":"2026-08-06T01:46:57.754827Z","end":"2026-08-06T01:46:58.072082Z","steps":["trace[1218570452] 'agreement among raft nodes before linearized reading' (duration: 317.099253ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:46:58.07214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:46:57.754796Z","time spent":"317.333729ms","remote":"127.0.0.1:34932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-08-06T01:46:58.072172Z","caller":"traceutil/trace.go:171","msg":"trace[325434900] transaction","detail":"{read_only:false; response_revision:22815; number_of_response:1; }","duration":"362.653368ms","start":"2026-08-06T01:46:57.709313Z","end":"2026-08-06T01:46:58.071967Z","steps":["trace[325434900] 'process raft request' (duration: 362.268967ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:46:58.072405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:46:57.709187Z","time spent":"363.10237ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:46:58.218531Z","caller":"traceutil/trace.go:171","msg":"trace[839352187] linearizableReadLoop","detail":"{readStateIndex:23586; appliedIndex:23585; }","duration":"146.61309ms","start":"2026-08-06T01:46:58.071888Z","end":"2026-08-06T01:46:58.218501Z","steps":["trace[839352187] 'read index received' (duration: 146.069015ms)","trace[839352187] 'applied index is now lower than readState.Index' (duration: 542.395µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:46:58.218615Z","caller":"traceutil/trace.go:171","msg":"trace[1600503833] transaction","detail":"{read_only:false; response_revision:22816; number_of_response:1; }","duration":"298.989771ms","start":"2026-08-06T01:46:57.919597Z","end":"2026-08-06T01:46:58.218586Z","steps":["trace[1600503833] 'process raft request' (duration: 298.491507ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:46:58.218798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.072098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-08-06T01:46:58.218855Z","caller":"traceutil/trace.go:171","msg":"trace[1390866486] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:22816; }","duration":"310.175762ms","start":"2026-08-06T01:46:57.908662Z","end":"2026-08-06T01:46:58.218838Z","steps":["trace[1390866486] 'agreement among raft nodes before linearized reading' (duration: 309.951066ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:46:58.218901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:46:57.90864Z","time spent":"310.251703ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":556,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-08-06T01:46:58.218901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.709823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T01:46:58.218961Z","caller":"traceutil/trace.go:171","msg":"trace[1315301163] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22816; }","duration":"267.829545ms","start":"2026-08-06T01:46:57.951117Z","end":"2026-08-06T01:46:58.218946Z","steps":["trace[1315301163] 'agreement among raft nodes before linearized reading' (duration: 267.695801ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:48:17.636774Z","caller":"traceutil/trace.go:171","msg":"trace[313945246] linearizableReadLoop","detail":"{readStateIndex:24485; appliedIndex:24484; }","duration":"117.221903ms","start":"2026-08-06T01:48:17.519513Z","end":"2026-08-06T01:48:17.636735Z","steps":["trace[313945246] 'read index received' (duration: 60.220241ms)","trace[313945246] 'applied index is now lower than readState.Index' (duration: 57.000272ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:48:17.636872Z","caller":"traceutil/trace.go:171","msg":"trace[2116476121] transaction","detail":"{read_only:false; response_revision:23695; number_of_response:1; }","duration":"178.574827ms","start":"2026-08-06T01:48:17.458259Z","end":"2026-08-06T01:48:17.636834Z","steps":["trace[2116476121] 'process raft request' (duration: 121.531093ms)","trace[2116476121] 'compare' (duration: 56.764866ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:48:17.637126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.596695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-06T01:48:17.637178Z","caller":"traceutil/trace.go:171","msg":"trace[1132752885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23695; }","duration":"117.747018ms","start":"2026-08-06T01:48:17.519416Z","end":"2026-08-06T01:48:17.637163Z","steps":["trace[1132752885] 'agreement among raft nodes before linearized reading' (duration: 117.47435ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:51:55.188141Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22782} {"level":"info","ts":"2026-08-06T01:51:55.323558Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22782,"took":"131.526024ms","hash":878286434,"current-db-size-bytes":57683968,"current-db-size":"58 MB","current-db-size-in-use-bytes":32747520,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-06T01:51:55.323628Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":878286434,"revision":22782,"compact-revision":19996} {"level":"info","ts":"2026-08-06T01:56:55.196421Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25341} {"level":"info","ts":"2026-08-06T01:56:55.329576Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25341,"took":"127.268871ms","hash":1478519462,"current-db-size-bytes":57683968,"current-db-size":"58 MB","current-db-size-in-use-bytes":29339648,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-06T01:56:55.329663Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1478519462,"revision":25341,"compact-revision":22782}