{"level":"warn","ts":"2026-09-07T18:21:36.271016Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-07T18:21:36.271131Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.104: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://162.253.55.104:2380","--initial-cluster=instance=https://162.253.55.104:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.104:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.104: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-09-07T18:21:36.271216Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-07T18:21:36.27123Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.104:2380"]} {"level":"info","ts":"2026-09-07T18:21:36.27125Z","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-09-07T18:21:36.271925Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.104:2379"]} {"level":"info","ts":"2026-09-07T18:21:36.272023Z","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://162.253.55.104:2380"],"listen-peer-urls":["https://162.253.55.104:2380"],"advertise-client-urls":["https://162.253.55.104:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.104:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.104: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-09-07T18:21:36.276418Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.042546ms"} {"level":"info","ts":"2026-09-07T18:21:36.282191Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8572cc3a5351216d","cluster-id":"c1a156b8f69e6a79"} {"level":"info","ts":"2026-09-07T18:21:36.282283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d switched to configuration voters=()"} {"level":"info","ts":"2026-09-07T18:21:36.282307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d became follower at term 0"} {"level":"info","ts":"2026-09-07T18:21:36.28232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8572cc3a5351216d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-07T18:21:36.28233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d became follower at term 1"} {"level":"info","ts":"2026-09-07T18:21:36.282377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d switched to configuration voters=(9615972705267425645)"} {"level":"warn","ts":"2026-09-07T18:21:36.288086Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-07T18:21:36.289832Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-07T18:21:36.29114Z","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-09-07T18:21:36.293638Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8572cc3a5351216d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-07T18:21:36.2938Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8572cc3a5351216d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-07T18:21:36.293979Z","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-09-07T18:21:36.294061Z","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-09-07T18:21:36.294074Z","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-09-07T18:21:36.296603Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d switched to configuration voters=(9615972705267425645)"} {"level":"info","ts":"2026-09-07T18:21:36.296875Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c1a156b8f69e6a79","local-member-id":"8572cc3a5351216d","added-peer-id":"8572cc3a5351216d","added-peer-peer-urls":["https://162.253.55.104:2380"]} {"level":"info","ts":"2026-09-07T18:21:36.29877Z","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-09-07T18:21:36.299057Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.104:2380"} {"level":"info","ts":"2026-09-07T18:21:36.299121Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8572cc3a5351216d","initial-advertise-peer-urls":["https://162.253.55.104:2380"],"listen-peer-urls":["https://162.253.55.104:2380"],"advertise-client-urls":["https://162.253.55.104:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.104:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-07T18:21:36.299205Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-07T18:21:36.299177Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.104:2380"} {"level":"info","ts":"2026-09-07T18:21:36.582771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d is starting a new election at term 1"} {"level":"info","ts":"2026-09-07T18:21:36.58284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d became pre-candidate at term 1"} {"level":"info","ts":"2026-09-07T18:21:36.582888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d received MsgPreVoteResp from 8572cc3a5351216d at term 1"} {"level":"info","ts":"2026-09-07T18:21:36.582962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d became candidate at term 2"} {"level":"info","ts":"2026-09-07T18:21:36.582974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d received MsgVoteResp from 8572cc3a5351216d at term 2"} {"level":"info","ts":"2026-09-07T18:21:36.582993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d became leader at term 2"} {"level":"info","ts":"2026-09-07T18:21:36.583005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8572cc3a5351216d elected leader 8572cc3a5351216d at term 2"} {"level":"info","ts":"2026-09-07T18:21:36.58434Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T18:21:36.585336Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T18:21:36.585335Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8572cc3a5351216d","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.104:2379]}","request-path":"/0/members/8572cc3a5351216d/attributes","cluster-id":"c1a156b8f69e6a79","publish-timeout":"7s"} {"level":"info","ts":"2026-09-07T18:21:36.58541Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T18:21:36.585843Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-07T18:21:36.585895Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-07T18:21:36.586008Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c1a156b8f69e6a79","local-member-id":"8572cc3a5351216d","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T18:21:36.586153Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T18:21:36.5862Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T18:21:36.588391Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.104:2379"} {"level":"info","ts":"2026-09-07T18:21:36.588651Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-07T18:22:22.739141Z","caller":"traceutil/trace.go:171","msg":"trace[1329582424] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"198.886105ms","start":"2026-09-07T18:22:22.540232Z","end":"2026-09-07T18:22:22.739118Z","steps":["trace[1329582424] 'process raft request' (duration: 198.67766ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:22:22.774905Z","caller":"traceutil/trace.go:171","msg":"trace[1709516107] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"226.486762ms","start":"2026-09-07T18:22:22.54839Z","end":"2026-09-07T18:22:22.774877Z","steps":["trace[1709516107] 'process raft request' (duration: 226.330978ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:22:22.774999Z","caller":"traceutil/trace.go:171","msg":"trace[231162046] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"226.190814ms","start":"2026-09-07T18:22:22.548741Z","end":"2026-09-07T18:22:22.774931Z","steps":["trace[231162046] 'process raft request' (duration: 226.076561ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:22:22.775174Z","caller":"traceutil/trace.go:171","msg":"trace[627646270] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"212.922269ms","start":"2026-09-07T18:22:22.562217Z","end":"2026-09-07T18:22:22.775139Z","steps":["trace[627646270] 'process raft request' (duration: 212.772545ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:22:22.775385Z","caller":"traceutil/trace.go:171","msg":"trace[2068789298] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"215.290551ms","start":"2026-09-07T18:22:22.56008Z","end":"2026-09-07T18:22:22.775371Z","steps":["trace[2068789298] 'process raft request' (duration: 214.785668ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:22:22.775407Z","caller":"traceutil/trace.go:171","msg":"trace[1021696153] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"209.156971ms","start":"2026-09-07T18:22:22.566238Z","end":"2026-09-07T18:22:22.775395Z","steps":["trace[1021696153] 'process raft request' (duration: 208.798231ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:22:22.775495Z","caller":"traceutil/trace.go:171","msg":"trace[1643544793] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"193.946567ms","start":"2026-09-07T18:22:22.581538Z","end":"2026-09-07T18:22:22.775484Z","steps":["trace[1643544793] 'process raft request' (duration: 193.634629ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:22:22.775498Z","caller":"traceutil/trace.go:171","msg":"trace[284965018] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"207.575ms","start":"2026-09-07T18:22:22.567905Z","end":"2026-09-07T18:22:22.77548Z","steps":["trace[284965018] 'process raft request' (duration: 207.214211ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:22:22.77543Z","caller":"traceutil/trace.go:171","msg":"trace[975770850] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"208.72749ms","start":"2026-09-07T18:22:22.566658Z","end":"2026-09-07T18:22:22.775386Z","steps":["trace[975770850] 'process raft request' (duration: 208.418562ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:22:22.775573Z","caller":"traceutil/trace.go:171","msg":"trace[1594033028] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:561; }","duration":"104.862432ms","start":"2026-09-07T18:22:22.670693Z","end":"2026-09-07T18:22:22.775556Z","steps":["trace[1594033028] 'read index received' (duration: 68.304723ms)","trace[1594033028] 'applied index is now lower than readState.Index' (duration: 36.556939ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:22:22.77561Z","caller":"traceutil/trace.go:171","msg":"trace[773077005] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"190.572119ms","start":"2026-09-07T18:22:22.585025Z","end":"2026-09-07T18:22:22.775597Z","steps":["trace[773077005] 'process raft request' (duration: 190.318763ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:22:22.775616Z","caller":"traceutil/trace.go:171","msg":"trace[6529555] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"137.932573ms","start":"2026-09-07T18:22:22.637674Z","end":"2026-09-07T18:22:22.775607Z","steps":["trace[6529555] 'process raft request' (duration: 137.796439ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:22:22.775701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.996576ms","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-09-07T18:22:22.775772Z","caller":"traceutil/trace.go:171","msg":"trace[1390125585] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:553; }","duration":"105.11476ms","start":"2026-09-07T18:22:22.670646Z","end":"2026-09-07T18:22:22.775761Z","steps":["trace[1390125585] 'agreement among raft nodes before linearized reading' (duration: 104.976426ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:22:29.239295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.824522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:22:29.239388Z","caller":"traceutil/trace.go:171","msg":"trace[632074083] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"171.972766ms","start":"2026-09-07T18:22:29.067393Z","end":"2026-09-07T18:22:29.239366Z","steps":["trace[632074083] 'range keys from in-memory index tree' (duration: 171.700549ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:22:29.512665Z","caller":"traceutil/trace.go:171","msg":"trace[1535904233] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"254.721426ms","start":"2026-09-07T18:22:29.257916Z","end":"2026-09-07T18:22:29.512637Z","steps":["trace[1535904233] 'process raft request' (duration: 254.572712ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:22:29.668705Z","caller":"traceutil/trace.go:171","msg":"trace[845089704] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"213.644289ms","start":"2026-09-07T18:22:29.455031Z","end":"2026-09-07T18:22:29.668675Z","steps":["trace[845089704] 'process raft request' (duration: 213.036213ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:22:30.015391Z","caller":"traceutil/trace.go:171","msg":"trace[1551466669] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"382.072423ms","start":"2026-09-07T18:22:29.633294Z","end":"2026-09-07T18:22:30.015367Z","steps":["trace[1551466669] 'process raft request' (duration: 381.910618ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:22:30.016079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:22:29.63326Z","time spent":"382.208656ms","remote":"127.0.0.1:36130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T18:22:30.098213Z","caller":"traceutil/trace.go:171","msg":"trace[889432030] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"346.92317ms","start":"2026-09-07T18:22:29.751268Z","end":"2026-09-07T18:22:30.098192Z","steps":["trace[889432030] 'process raft request' (duration: 346.823609ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:22:30.098322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:22:29.751241Z","time spent":"347.032402ms","remote":"127.0.0.1:36352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-07T18:22:30.233595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.459209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:22:30.233707Z","caller":"traceutil/trace.go:171","msg":"trace[2081798014] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:653; }","duration":"120.598582ms","start":"2026-09-07T18:22:30.113067Z","end":"2026-09-07T18:22:30.233665Z","steps":["trace[2081798014] 'range keys from in-memory index tree' (duration: 120.328875ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:23:34.995163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.894557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-07T18:23:34.995279Z","caller":"traceutil/trace.go:171","msg":"trace[1457591465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:962; }","duration":"284.050591ms","start":"2026-09-07T18:23:34.711187Z","end":"2026-09-07T18:23:34.995238Z","steps":["trace[1457591465] 'range keys from in-memory index tree' (duration: 283.701432ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:23:34.995351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.42635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-07T18:23:34.995425Z","caller":"traceutil/trace.go:171","msg":"trace[134506770] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:962; }","duration":"237.551283ms","start":"2026-09-07T18:23:34.757857Z","end":"2026-09-07T18:23:34.995408Z","steps":["trace[134506770] 'range keys from in-memory index tree' (duration: 237.179984ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:23:48.179111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.088006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:23:48.179216Z","caller":"traceutil/trace.go:171","msg":"trace[840143623] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1125; }","duration":"111.22642ms","start":"2026-09-07T18:23:48.067968Z","end":"2026-09-07T18:23:48.179195Z","steps":["trace[840143623] 'range keys from in-memory index tree' (duration: 111.013935ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:23:48.179188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.592644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-07T18:23:48.179281Z","caller":"traceutil/trace.go:171","msg":"trace[712165898] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1125; }","duration":"140.738378ms","start":"2026-09-07T18:23:48.03852Z","end":"2026-09-07T18:23:48.179258Z","steps":["trace[712165898] 'range keys from in-memory index tree' (duration: 140.360508ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:24:04.451997Z","caller":"traceutil/trace.go:171","msg":"trace[2067198401] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"174.456773ms","start":"2026-09-07T18:24:04.277517Z","end":"2026-09-07T18:24:04.451974Z","steps":["trace[2067198401] 'process raft request' (duration: 174.318809ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:24:51.603231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.49322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-07T18:24:51.603373Z","caller":"traceutil/trace.go:171","msg":"trace[1454774209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2024; }","duration":"233.682305ms","start":"2026-09-07T18:24:51.369669Z","end":"2026-09-07T18:24:51.603352Z","steps":["trace[1454774209] 'range keys from in-memory index tree' (duration: 233.287324ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:24:51.60356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.380327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-07T18:24:51.603685Z","caller":"traceutil/trace.go:171","msg":"trace[1641666305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2024; }","duration":"233.579222ms","start":"2026-09-07T18:24:51.370075Z","end":"2026-09-07T18:24:51.603654Z","steps":["trace[1641666305] 'range keys from in-memory index tree' (duration: 233.12455ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:24:51.611136Z","caller":"traceutil/trace.go:171","msg":"trace[711355348] transaction","detail":"{read_only:false; response_revision:2025; number_of_response:1; }","duration":"228.007283ms","start":"2026-09-07T18:24:51.383107Z","end":"2026-09-07T18:24:51.611114Z","steps":["trace[711355348] 'process raft request' (duration: 227.843139ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:24:51.770868Z","caller":"traceutil/trace.go:171","msg":"trace[390279692] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"132.144023ms","start":"2026-09-07T18:24:51.638705Z","end":"2026-09-07T18:24:51.77085Z","steps":["trace[390279692] 'process raft request' (duration: 132.008179ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:24:51.770849Z","caller":"traceutil/trace.go:171","msg":"trace[1198873200] transaction","detail":"{read_only:false; response_revision:2029; number_of_response:1; }","duration":"132.349669ms","start":"2026-09-07T18:24:51.638469Z","end":"2026-09-07T18:24:51.770819Z","steps":["trace[1198873200] 'process raft request' (duration: 101.09517ms)","trace[1198873200] 'compare' (duration: 31.004752ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:24:51.77079Z","caller":"traceutil/trace.go:171","msg":"trace[459910900] linearizableReadLoop","detail":"{readStateIndex:2114; appliedIndex:2112; }","duration":"104.630852ms","start":"2026-09-07T18:24:51.666133Z","end":"2026-09-07T18:24:51.770764Z","steps":["trace[459910900] 'read index received' (duration: 73.371103ms)","trace[459910900] 'applied index is now lower than readState.Index' (duration: 31.258879ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:24:51.771107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.923741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T18:24:51.771176Z","caller":"traceutil/trace.go:171","msg":"trace[508424642] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2030; }","duration":"105.063954ms","start":"2026-09-07T18:24:51.666094Z","end":"2026-09-07T18:24:51.771158Z","steps":["trace[508424642] 'agreement among raft nodes before linearized reading' (duration: 104.891979ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:24:52.029724Z","caller":"traceutil/trace.go:171","msg":"trace[170339762] transaction","detail":"{read_only:false; response_revision:2032; number_of_response:1; }","duration":"190.564761ms","start":"2026-09-07T18:24:51.839124Z","end":"2026-09-07T18:24:52.029689Z","steps":["trace[170339762] 'process raft request' (duration: 123.178545ms)","trace[170339762] 'compare' (duration: 66.952565ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:25:06.004583Z","caller":"traceutil/trace.go:171","msg":"trace[491823941] linearizableReadLoop","detail":"{readStateIndex:2235; appliedIndex:2234; }","duration":"160.223807ms","start":"2026-09-07T18:25:05.844336Z","end":"2026-09-07T18:25:06.004559Z","steps":["trace[491823941] 'read index received' (duration: 160.045672ms)","trace[491823941] 'applied index is now lower than readState.Index' (duration: 176.825µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:25:06.004647Z","caller":"traceutil/trace.go:171","msg":"trace[175177118] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"161.828249ms","start":"2026-09-07T18:25:05.842782Z","end":"2026-09-07T18:25:06.00461Z","steps":["trace[175177118] 'process raft request' (duration: 161.621534ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:25:06.004825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.466634ms","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-09-07T18:25:06.004866Z","caller":"traceutil/trace.go:171","msg":"trace[431759957] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2147; }","duration":"160.560897ms","start":"2026-09-07T18:25:05.844294Z","end":"2026-09-07T18:25:06.004855Z","steps":["trace[431759957] 'agreement among raft nodes before linearized reading' (duration: 160.394202ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:25:06.004994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.795197ms","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-09-07T18:25:06.005067Z","caller":"traceutil/trace.go:171","msg":"trace[601426997] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2147; }","duration":"133.921841ms","start":"2026-09-07T18:25:05.871126Z","end":"2026-09-07T18:25:06.005048Z","steps":["trace[601426997] 'agreement among raft nodes before linearized reading' (duration: 133.632923ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:25:37.221842Z","caller":"traceutil/trace.go:171","msg":"trace[1774056068] transaction","detail":"{read_only:false; response_revision:2330; number_of_response:1; }","duration":"151.470021ms","start":"2026-09-07T18:25:37.070345Z","end":"2026-09-07T18:25:37.221815Z","steps":["trace[1774056068] 'process raft request' (duration: 151.341597ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:27:10.281076Z","caller":"traceutil/trace.go:171","msg":"trace[2058673855] transaction","detail":"{read_only:false; response_revision:3028; number_of_response:1; }","duration":"116.390608ms","start":"2026-09-07T18:27:10.164645Z","end":"2026-09-07T18:27:10.281036Z","steps":["trace[2058673855] 'process raft request' (duration: 116.245714ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:27:10.557797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.523437ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-09-07T18:27:10.557871Z","caller":"traceutil/trace.go:171","msg":"trace[1582648204] linearizableReadLoop","detail":"{readStateIndex:3149; appliedIndex:3148; }","duration":"216.895957ms","start":"2026-09-07T18:27:10.340963Z","end":"2026-09-07T18:27:10.557859Z","steps":["trace[1582648204] 'read index received' (duration: 110.146284ms)","trace[1582648204] 'applied index is now lower than readState.Index' (duration: 106.748793ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:27:10.558141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.175754ms","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-09-07T18:27:10.558166Z","caller":"traceutil/trace.go:171","msg":"trace[844512713] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3028; }","duration":"217.228375ms","start":"2026-09-07T18:27:10.340931Z","end":"2026-09-07T18:27:10.558159Z","steps":["trace[844512713] 'agreement among raft nodes before linearized reading' (duration: 217.105632ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:27:10.558808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.094834ms","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-09-07T18:27:10.558887Z","caller":"traceutil/trace.go:171","msg":"trace[596365175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3028; }","duration":"115.206186ms","start":"2026-09-07T18:27:10.443664Z","end":"2026-09-07T18:27:10.55887Z","steps":["trace[596365175] 'agreement among raft nodes before linearized reading' (duration: 114.556509ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:27:10.564487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.765452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-07T18:27:10.564604Z","caller":"traceutil/trace.go:171","msg":"trace[396692142] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3028; }","duration":"120.919637ms","start":"2026-09-07T18:27:10.443667Z","end":"2026-09-07T18:27:10.564586Z","steps":["trace[396692142] 'agreement among raft nodes before linearized reading' (duration: 120.703951ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:31:36.927532Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2827} {"level":"warn","ts":"2026-09-07T18:31:37.040116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.883607ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-07T18:31:37.040414Z","caller":"traceutil/trace.go:171","msg":"trace[2060551719] transaction","detail":"{read_only:false; response_revision:5143; number_of_response:1; }","duration":"106.53079ms","start":"2026-09-07T18:31:36.933858Z","end":"2026-09-07T18:31:37.040388Z","steps":["trace[2060551719] 'compare' (duration: 73.496868ms)","trace[2060551719] 'marshal mvccpb.KeyValue' {req_type:put; key:/registry/events/local-path-storage/helper-pod-create-pvc-b3456dd4-96ef-4c5c-a948-89e76869a53e.18d31d7c5ab6bf5c; req_size:850; } (duration: 28.239255ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:31:37.040585Z","caller":"traceutil/trace.go:171","msg":"trace[1563962872] transaction","detail":"{read_only:false; response_revision:5144; number_of_response:1; }","duration":"102.138524ms","start":"2026-09-07T18:31:36.938407Z","end":"2026-09-07T18:31:37.040545Z","steps":["trace[1563962872] 'process raft request' (duration: 101.795765ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:31:37.087255Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2827,"took":"158.253204ms","hash":791876462,"current-db-size-bytes":50479104,"current-db-size":"50 MB","current-db-size-in-use-bytes":27697152,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-07T18:31:37.087312Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":791876462,"revision":2827,"compact-revision":-1} {"level":"info","ts":"2026-09-07T18:31:42.09372Z","caller":"traceutil/trace.go:171","msg":"trace[1830851567] linearizableReadLoop","detail":"{readStateIndex:5466; appliedIndex:5464; }","duration":"103.287135ms","start":"2026-09-07T18:31:41.990398Z","end":"2026-09-07T18:31:42.093685Z","steps":["trace[1830851567] 'read index received' (duration: 12.424939ms)","trace[1830851567] 'applied index is now lower than readState.Index' (duration: 90.860806ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:31:42.093753Z","caller":"traceutil/trace.go:171","msg":"trace[1690725732] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5259; }","duration":"106.054428ms","start":"2026-09-07T18:31:41.987657Z","end":"2026-09-07T18:31:42.093712Z","steps":["trace[1690725732] 'process raft request' (duration: 47.475313ms)","trace[1690725732] 'compare' (duration: 58.459982ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:31:42.093936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.502011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:31:42.093975Z","caller":"traceutil/trace.go:171","msg":"trace[1870031368] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:5259; }","duration":"103.591823ms","start":"2026-09-07T18:31:41.990373Z","end":"2026-09-07T18:31:42.093964Z","steps":["trace[1870031368] 'agreement among raft nodes before linearized reading' (duration: 103.48331ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:31:42.110962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.185864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:23 size:531300"} {"level":"info","ts":"2026-09-07T18:31:42.111032Z","caller":"traceutil/trace.go:171","msg":"trace[315177709] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:23; response_revision:5260; }","duration":"108.293877ms","start":"2026-09-07T18:31:42.002718Z","end":"2026-09-07T18:31:42.111012Z","steps":["trace[315177709] 'agreement among raft nodes before linearized reading' (duration: 107.987128ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:31:42.111384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.880581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-09-07T18:31:42.111421Z","caller":"traceutil/trace.go:171","msg":"trace[188435952] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5260; }","duration":"111.942963ms","start":"2026-09-07T18:31:41.999469Z","end":"2026-09-07T18:31:42.111412Z","steps":["trace[188435952] 'agreement among raft nodes before linearized reading' (duration: 111.851451ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:31:42.111712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.245339ms","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-09-07T18:31:42.111821Z","caller":"traceutil/trace.go:171","msg":"trace[1821229980] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5260; }","duration":"121.418264ms","start":"2026-09-07T18:31:41.99038Z","end":"2026-09-07T18:31:42.111798Z","steps":["trace[1821229980] 'agreement among raft nodes before linearized reading' (duration: 121.045594ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:31:47.246784Z","caller":"traceutil/trace.go:171","msg":"trace[306867499] transaction","detail":"{read_only:false; response_revision:5411; number_of_response:1; }","duration":"201.564047ms","start":"2026-09-07T18:31:47.045191Z","end":"2026-09-07T18:31:47.246755Z","steps":["trace[306867499] 'process raft request' (duration: 201.381092ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:31:47.411252Z","caller":"traceutil/trace.go:171","msg":"trace[1238515341] linearizableReadLoop","detail":"{readStateIndex:5623; appliedIndex:5622; }","duration":"341.351085ms","start":"2026-09-07T18:31:47.069852Z","end":"2026-09-07T18:31:47.411203Z","steps":["trace[1238515341] 'read index received' (duration: 177.538533ms)","trace[1238515341] 'applied index is now lower than readState.Index' (duration: 163.809482ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:31:47.411414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.53756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:31:47.411455Z","caller":"traceutil/trace.go:171","msg":"trace[1972948712] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5412; }","duration":"341.625343ms","start":"2026-09-07T18:31:47.069819Z","end":"2026-09-07T18:31:47.411444Z","steps":["trace[1972948712] 'agreement among raft nodes before linearized reading' (duration: 341.52509ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:31:47.411427Z","caller":"traceutil/trace.go:171","msg":"trace[1137961478] transaction","detail":"{read_only:false; response_revision:5412; number_of_response:1; }","duration":"346.442141ms","start":"2026-09-07T18:31:47.064927Z","end":"2026-09-07T18:31:47.411369Z","steps":["trace[1137961478] 'process raft request' (duration: 275.90819ms)","trace[1137961478] 'compare' (duration: 70.226422ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:31:47.411503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:31:47.069805Z","time spent":"341.690254ms","remote":"127.0.0.1:35884","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-09-07T18:31:47.411675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:31:47.064871Z","time spent":"346.672936ms","remote":"127.0.0.1:35946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58177,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T18:31:47.431861Z","caller":"traceutil/trace.go:171","msg":"trace[1942238783] transaction","detail":"{read_only:false; response_revision:5413; number_of_response:1; }","duration":"179.638329ms","start":"2026-09-07T18:31:47.252191Z","end":"2026-09-07T18:31:47.431829Z","steps":["trace[1942238783] 'process raft request' (duration: 179.435183ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:31:47.433173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.163273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:31:47.43326Z","caller":"traceutil/trace.go:171","msg":"trace[650584194] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:5414; }","duration":"158.287837ms","start":"2026-09-07T18:31:47.274957Z","end":"2026-09-07T18:31:47.433244Z","steps":["trace[650584194] 'agreement among raft nodes before linearized reading' (duration: 158.138923ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:31:47.433532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.714175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-90099107-2954-469c-b7de-a4232d58326b\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-09-07T18:31:47.43357Z","caller":"traceutil/trace.go:171","msg":"trace[1944098622] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-90099107-2954-469c-b7de-a4232d58326b; range_end:; response_count:1; response_revision:5414; }","duration":"330.801627ms","start":"2026-09-07T18:31:47.102758Z","end":"2026-09-07T18:31:47.433559Z","steps":["trace[1944098622] 'agreement among raft nodes before linearized reading' (duration: 330.712665ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:31:47.433597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:31:47.102715Z","time spent":"330.87391ms","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3625,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-90099107-2954-469c-b7de-a4232d58326b\" "} {"level":"warn","ts":"2026-09-07T18:31:47.433959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.511104ms","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-09-07T18:31:47.433989Z","caller":"traceutil/trace.go:171","msg":"trace[1436024083] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5414; }","duration":"340.602016ms","start":"2026-09-07T18:31:47.093378Z","end":"2026-09-07T18:31:47.43398Z","steps":["trace[1436024083] 'agreement among raft nodes before linearized reading' (duration: 340.472973ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:31:47.434014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:31:47.093322Z","time spent":"340.685848ms","remote":"127.0.0.1:36130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-07T18:31:47.80779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.598891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:31:47.807878Z","caller":"traceutil/trace.go:171","msg":"trace[388200276] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:5420; }","duration":"123.749245ms","start":"2026-09-07T18:31:47.684107Z","end":"2026-09-07T18:31:47.807856Z","steps":["trace[388200276] 'count revisions from in-memory index tree' (duration: 123.464777ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:31:47.807913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.949311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-09-07T18:31:47.807996Z","caller":"traceutil/trace.go:171","msg":"trace[2098217858] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:5420; }","duration":"146.075784ms","start":"2026-09-07T18:31:47.661895Z","end":"2026-09-07T18:31:47.807971Z","steps":["trace[2098217858] 'range keys from in-memory index tree' (duration: 145.803486ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:32:01.310325Z","caller":"traceutil/trace.go:171","msg":"trace[1709823472] linearizableReadLoop","detail":"{readStateIndex:5897; appliedIndex:5896; }","duration":"242.593266ms","start":"2026-09-07T18:32:01.0677Z","end":"2026-09-07T18:32:01.310293Z","steps":["trace[1709823472] 'read index received' (duration: 242.411001ms)","trace[1709823472] 'applied index is now lower than readState.Index' (duration: 181.105µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:32:01.310476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.75379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:32:01.310515Z","caller":"traceutil/trace.go:171","msg":"trace[1719880365] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5678; }","duration":"242.846252ms","start":"2026-09-07T18:32:01.067658Z","end":"2026-09-07T18:32:01.310504Z","steps":["trace[1719880365] 'agreement among raft nodes before linearized reading' (duration: 242.76027ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:32:01.310663Z","caller":"traceutil/trace.go:171","msg":"trace[1759228217] transaction","detail":"{read_only:false; response_revision:5678; number_of_response:1; }","duration":"268.388914ms","start":"2026-09-07T18:32:01.042264Z","end":"2026-09-07T18:32:01.310653Z","steps":["trace[1759228217] 'process raft request' (duration: 267.768258ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:32:01.494743Z","caller":"traceutil/trace.go:171","msg":"trace[1439125906] transaction","detail":"{read_only:false; response_revision:5679; number_of_response:1; }","duration":"179.908449ms","start":"2026-09-07T18:32:01.314805Z","end":"2026-09-07T18:32:01.494713Z","steps":["trace[1439125906] 'process raft request' (duration: 108.611476ms)","trace[1439125906] 'compare' (duration: 71.146449ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:32:01.542393Z","caller":"traceutil/trace.go:171","msg":"trace[80782770] transaction","detail":"{read_only:false; response_revision:5682; number_of_response:1; }","duration":"226.242476ms","start":"2026-09-07T18:32:01.316126Z","end":"2026-09-07T18:32:01.542368Z","steps":["trace[80782770] 'process raft request' (duration: 226.190765ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:32:01.542663Z","caller":"traceutil/trace.go:171","msg":"trace[66511932] transaction","detail":"{read_only:false; response_revision:5680; number_of_response:1; }","duration":"226.79501ms","start":"2026-09-07T18:32:01.315837Z","end":"2026-09-07T18:32:01.542632Z","steps":["trace[66511932] 'process raft request' (duration: 226.268246ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:32:01.542781Z","caller":"traceutil/trace.go:171","msg":"trace[1181954061] transaction","detail":"{read_only:false; response_revision:5681; number_of_response:1; }","duration":"226.677668ms","start":"2026-09-07T18:32:01.316059Z","end":"2026-09-07T18:32:01.542736Z","steps":["trace[1181954061] 'process raft request' (duration: 226.201976ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:32:09.199061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.64674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:32:09.199146Z","caller":"traceutil/trace.go:171","msg":"trace[276083924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5854; }","duration":"131.766583ms","start":"2026-09-07T18:32:09.067362Z","end":"2026-09-07T18:32:09.199128Z","steps":["trace[276083924] 'range keys from in-memory index tree' (duration: 131.597969ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:32:09.199322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.99358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-09-07T18:32:09.199422Z","caller":"traceutil/trace.go:171","msg":"trace[2137931702] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5854; }","duration":"140.185574ms","start":"2026-09-07T18:32:09.059214Z","end":"2026-09-07T18:32:09.1994Z","steps":["trace[2137931702] 'range keys from in-memory index tree' (duration: 139.747053ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:32:09.332133Z","caller":"traceutil/trace.go:171","msg":"trace[562827486] transaction","detail":"{read_only:false; response_revision:5855; number_of_response:1; }","duration":"122.122421ms","start":"2026-09-07T18:32:09.209986Z","end":"2026-09-07T18:32:09.332108Z","steps":["trace[562827486] 'process raft request' (duration: 121.945996ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:32:09.6224Z","caller":"traceutil/trace.go:171","msg":"trace[610553173] transaction","detail":"{read_only:false; response_revision:5858; number_of_response:1; }","duration":"185.509026ms","start":"2026-09-07T18:32:09.436866Z","end":"2026-09-07T18:32:09.622375Z","steps":["trace[610553173] 'process raft request' (duration: 185.241549ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:32:09.622415Z","caller":"traceutil/trace.go:171","msg":"trace[280530785] transaction","detail":"{read_only:false; response_revision:5859; number_of_response:1; }","duration":"185.471705ms","start":"2026-09-07T18:32:09.436918Z","end":"2026-09-07T18:32:09.62239Z","steps":["trace[280530785] 'process raft request' (duration: 185.359852ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:32:09.622702Z","caller":"traceutil/trace.go:171","msg":"trace[1253412858] transaction","detail":"{read_only:false; response_revision:5860; number_of_response:1; }","duration":"185.747612ms","start":"2026-09-07T18:32:09.436939Z","end":"2026-09-07T18:32:09.622687Z","steps":["trace[1253412858] 'process raft request' (duration: 185.384773ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:32:09.622745Z","caller":"traceutil/trace.go:171","msg":"trace[1903557205] transaction","detail":"{read_only:false; response_revision:5861; number_of_response:1; }","duration":"185.600748ms","start":"2026-09-07T18:32:09.437127Z","end":"2026-09-07T18:32:09.622727Z","steps":["trace[1903557205] 'process raft request' (duration: 185.418483ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:32:09.622746Z","caller":"traceutil/trace.go:171","msg":"trace[701946444] transaction","detail":"{read_only:false; response_revision:5862; number_of_response:1; }","duration":"183.330719ms","start":"2026-09-07T18:32:09.439388Z","end":"2026-09-07T18:32:09.622719Z","steps":["trace[701946444] 'process raft request' (duration: 183.255277ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:32:19.823925Z","caller":"traceutil/trace.go:171","msg":"trace[1243275637] transaction","detail":"{read_only:false; response_revision:5976; number_of_response:1; }","duration":"131.008964ms","start":"2026-09-07T18:32:19.692889Z","end":"2026-09-07T18:32:19.823898Z","steps":["trace[1243275637] 'process raft request' (duration: 85.520668ms)","trace[1243275637] 'compare' (duration: 45.353452ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:36:36.935568Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5141} {"level":"info","ts":"2026-09-07T18:36:37.021704Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5141,"took":"83.785216ms","hash":3229659189,"current-db-size-bytes":50479104,"current-db-size":"50 MB","current-db-size-in-use-bytes":28262400,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-07T18:36:37.021843Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3229659189,"revision":5141,"compact-revision":2827} {"level":"info","ts":"2026-09-07T18:38:08.625007Z","caller":"traceutil/trace.go:171","msg":"trace[631474985] linearizableReadLoop","detail":"{readStateIndex:8942; appliedIndex:8941; }","duration":"497.031122ms","start":"2026-09-07T18:38:08.127953Z","end":"2026-09-07T18:38:08.624984Z","steps":["trace[631474985] 'read index received' (duration: 496.859697ms)","trace[631474985] 'applied index is now lower than readState.Index' (duration: 169.935µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:38:08.625451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.371978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-09-07T18:38:08.625484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.28461ms","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-09-07T18:38:08.625542Z","caller":"traceutil/trace.go:171","msg":"trace[875056436] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8631; }","duration":"487.499311ms","start":"2026-09-07T18:38:08.138014Z","end":"2026-09-07T18:38:08.625514Z","steps":["trace[875056436] 'agreement among raft nodes before linearized reading' (duration: 487.176532ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:38:08.625549Z","caller":"traceutil/trace.go:171","msg":"trace[654324092] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8631; }","duration":"483.367022ms","start":"2026-09-07T18:38:08.14216Z","end":"2026-09-07T18:38:08.625527Z","steps":["trace[654324092] 'agreement among raft nodes before linearized reading' (duration: 483.151897ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:38:08.625559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.42661ms","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":"warn","ts":"2026-09-07T18:38:08.625581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:38:08.137994Z","time spent":"487.579284ms","remote":"127.0.0.1:36130","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-09-07T18:38:08.625583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:38:08.142149Z","time spent":"483.425634ms","remote":"127.0.0.1:36130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-07T18:38:08.625565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.548541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-07T18:38:08.625581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.750048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-07T18:38:08.625637Z","caller":"traceutil/trace.go:171","msg":"trace[386548278] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8631; }","duration":"294.567204ms","start":"2026-09-07T18:38:08.331044Z","end":"2026-09-07T18:38:08.625612Z","steps":["trace[386548278] 'agreement among raft nodes before linearized reading' (duration: 294.176023ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:38:08.625656Z","caller":"traceutil/trace.go:171","msg":"trace[230571889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8631; }","duration":"425.665713ms","start":"2026-09-07T18:38:08.19997Z","end":"2026-09-07T18:38:08.625635Z","steps":["trace[230571889] 'agreement among raft nodes before linearized reading' (duration: 425.240932ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:38:08.625676Z","caller":"traceutil/trace.go:171","msg":"trace[774088147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8631; }","duration":"438.886592ms","start":"2026-09-07T18:38:08.186763Z","end":"2026-09-07T18:38:08.62565Z","steps":["trace[774088147] 'agreement among raft nodes before linearized reading' (duration: 438.509122ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:38:08.625701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:38:08.199959Z","time spent":"425.731166ms","remote":"127.0.0.1:36130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-07T18:38:08.625719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:38:08.186742Z","time spent":"438.968784ms","remote":"127.0.0.1:36130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-07T18:38:08.625085Z","caller":"traceutil/trace.go:171","msg":"trace[757812160] transaction","detail":"{read_only:false; response_revision:8631; number_of_response:1; }","duration":"532.798623ms","start":"2026-09-07T18:38:08.09224Z","end":"2026-09-07T18:38:08.625038Z","steps":["trace[757812160] 'process raft request' (duration: 532.590528ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:38:08.625935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:38:08.092211Z","time spent":"533.631975ms","remote":"127.0.0.1:36130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T18:38:08.625952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.982027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-07T18:38:08.625993Z","caller":"traceutil/trace.go:171","msg":"trace[974932402] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8631; }","duration":"498.054949ms","start":"2026-09-07T18:38:08.127925Z","end":"2026-09-07T18:38:08.62598Z","steps":["trace[974932402] 'agreement among raft nodes before linearized reading' (duration: 497.222437ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:38:08.626022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:38:08.127912Z","time spent":"498.10344ms","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-07T18:38:20.495479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.434088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.104\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-07T18:38:20.49557Z","caller":"traceutil/trace.go:171","msg":"trace[1540974145] range","detail":"{range_begin:/registry/masterleases/162.253.55.104; range_end:; response_count:1; response_revision:8693; }","duration":"126.573392ms","start":"2026-09-07T18:38:20.368972Z","end":"2026-09-07T18:38:20.495545Z","steps":["trace[1540974145] 'range keys from in-memory index tree' (duration: 126.271964ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:38:21.217151Z","caller":"traceutil/trace.go:171","msg":"trace[226971145] transaction","detail":"{read_only:false; response_revision:8701; number_of_response:1; }","duration":"162.480967ms","start":"2026-09-07T18:38:21.054636Z","end":"2026-09-07T18:38:21.217117Z","steps":["trace[226971145] 'process raft request' (duration: 162.315804ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:38:21.763738Z","caller":"traceutil/trace.go:171","msg":"trace[1295384514] transaction","detail":"{read_only:false; response_revision:8702; number_of_response:1; }","duration":"160.158075ms","start":"2026-09-07T18:38:21.603556Z","end":"2026-09-07T18:38:21.763714Z","steps":["trace[1295384514] 'process raft request' (duration: 159.978811ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:38:49.500632Z","caller":"traceutil/trace.go:171","msg":"trace[744985011] transaction","detail":"{read_only:false; response_revision:8906; number_of_response:1; }","duration":"141.561594ms","start":"2026-09-07T18:38:49.359053Z","end":"2026-09-07T18:38:49.500614Z","steps":["trace[744985011] 'process raft request' (duration: 141.444081ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:38:49.75957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.743858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-07T18:38:49.759642Z","caller":"traceutil/trace.go:171","msg":"trace[566789325] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8906; }","duration":"218.877112ms","start":"2026-09-07T18:38:49.540748Z","end":"2026-09-07T18:38:49.759625Z","steps":["trace[566789325] 'range keys from in-memory index tree' (duration: 218.538023ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:38:51.079278Z","caller":"traceutil/trace.go:171","msg":"trace[56238984] linearizableReadLoop","detail":"{readStateIndex:9236; appliedIndex:9235; }","duration":"149.706269ms","start":"2026-09-07T18:38:50.929549Z","end":"2026-09-07T18:38:51.079255Z","steps":["trace[56238984] 'read index received' (duration: 140.861105ms)","trace[56238984] 'applied index is now lower than readState.Index' (duration: 8.844194ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:38:51.079327Z","caller":"traceutil/trace.go:171","msg":"trace[1477718339] transaction","detail":"{read_only:false; response_revision:8913; number_of_response:1; }","duration":"156.534528ms","start":"2026-09-07T18:38:50.922772Z","end":"2026-09-07T18:38:51.079306Z","steps":["trace[1477718339] 'process raft request' (duration: 147.689214ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:38:51.079563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.477965ms","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-09-07T18:38:51.079602Z","caller":"traceutil/trace.go:171","msg":"trace[222658] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8913; }","duration":"148.546357ms","start":"2026-09-07T18:38:50.931046Z","end":"2026-09-07T18:38:51.079593Z","steps":["trace[222658] 'agreement among raft nodes before linearized reading' (duration: 148.429664ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:38:51.079668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.684383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pp5bg\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-09-07T18:38:51.079751Z","caller":"traceutil/trace.go:171","msg":"trace[919560343] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pp5bg; range_end:; response_count:1; response_revision:8913; }","duration":"128.782106ms","start":"2026-09-07T18:38:50.950951Z","end":"2026-09-07T18:38:51.079733Z","steps":["trace[919560343] 'agreement among raft nodes before linearized reading' (duration: 128.569151ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:38:51.079802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.305678ms","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-09-07T18:38:51.079849Z","caller":"traceutil/trace.go:171","msg":"trace[1346672212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8913; }","duration":"146.396969ms","start":"2026-09-07T18:38:50.933439Z","end":"2026-09-07T18:38:51.079836Z","steps":["trace[1346672212] 'agreement among raft nodes before linearized reading' (duration: 146.259066ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:38:51.080114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.56087ms","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-09-07T18:38:51.08015Z","caller":"traceutil/trace.go:171","msg":"trace[270348130] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8913; }","duration":"150.594811ms","start":"2026-09-07T18:38:50.929543Z","end":"2026-09-07T18:38:51.080138Z","steps":["trace[270348130] 'agreement among raft nodes before linearized reading' (duration: 149.854872ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:38:57.245704Z","caller":"traceutil/trace.go:171","msg":"trace[196160718] linearizableReadLoop","detail":"{readStateIndex:9270; appliedIndex:9269; }","duration":"125.264502ms","start":"2026-09-07T18:38:57.120414Z","end":"2026-09-07T18:38:57.245679Z","steps":["trace[196160718] 'read index received' (duration: 125.031656ms)","trace[196160718] 'applied index is now lower than readState.Index' (duration: 231.406µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:38:57.245863Z","caller":"traceutil/trace.go:171","msg":"trace[1628795843] transaction","detail":"{read_only:false; response_revision:8946; number_of_response:1; }","duration":"136.201111ms","start":"2026-09-07T18:38:57.109548Z","end":"2026-09-07T18:38:57.245749Z","steps":["trace[1628795843] 'process raft request' (duration: 135.984675ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:38:57.246003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.30203ms","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":"warn","ts":"2026-09-07T18:38:57.245992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.553351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pp5bg\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-09-07T18:38:57.246071Z","caller":"traceutil/trace.go:171","msg":"trace[724337228] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8946; }","duration":"115.402853ms","start":"2026-09-07T18:38:57.130648Z","end":"2026-09-07T18:38:57.246051Z","steps":["trace[724337228] 'agreement among raft nodes before linearized reading' (duration: 115.203568ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:38:57.246101Z","caller":"traceutil/trace.go:171","msg":"trace[1312671801] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pp5bg; range_end:; response_count:1; response_revision:8946; }","duration":"125.709465ms","start":"2026-09-07T18:38:57.120369Z","end":"2026-09-07T18:38:57.246078Z","steps":["trace[1312671801] 'agreement among raft nodes before linearized reading' (duration: 125.414187ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:38:57.246035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.230827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-09-07T18:38:57.246135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.19881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-07T18:38:57.246231Z","caller":"traceutil/trace.go:171","msg":"trace[110516958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8946; }","duration":"109.331512ms","start":"2026-09-07T18:38:57.136878Z","end":"2026-09-07T18:38:57.246209Z","steps":["trace[110516958] 'agreement among raft nodes before linearized reading' (duration: 109.035535ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:38:57.246176Z","caller":"traceutil/trace.go:171","msg":"trace[669094004] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8946; }","duration":"107.421083ms","start":"2026-09-07T18:38:57.138743Z","end":"2026-09-07T18:38:57.246164Z","steps":["trace[669094004] 'agreement among raft nodes before linearized reading' (duration: 107.136326ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:38:57.403516Z","caller":"traceutil/trace.go:171","msg":"trace[1546505737] transaction","detail":"{read_only:false; response_revision:8947; number_of_response:1; }","duration":"152.006649ms","start":"2026-09-07T18:38:57.251488Z","end":"2026-09-07T18:38:57.403495Z","steps":["trace[1546505737] 'process raft request' (duration: 151.859525ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:38:57.590638Z","caller":"traceutil/trace.go:171","msg":"trace[1700838180] transaction","detail":"{read_only:false; response_revision:8948; number_of_response:1; }","duration":"338.617108ms","start":"2026-09-07T18:38:57.251991Z","end":"2026-09-07T18:38:57.590608Z","steps":["trace[1700838180] 'process raft request' (duration: 309.274014ms)","trace[1700838180] 'compare' (duration: 29.19269ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:38:57.590893Z","caller":"traceutil/trace.go:171","msg":"trace[1992550856] linearizableReadLoop","detail":"{readStateIndex:9272; appliedIndex:9270; }","duration":"338.711291ms","start":"2026-09-07T18:38:57.252159Z","end":"2026-09-07T18:38:57.59087Z","steps":["trace[1992550856] 'read index received' (duration: 151.168706ms)","trace[1992550856] 'applied index is now lower than readState.Index' (duration: 187.541285ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:38:57.590923Z","caller":"traceutil/trace.go:171","msg":"trace[1378939284] transaction","detail":"{read_only:false; response_revision:8949; number_of_response:1; }","duration":"337.324345ms","start":"2026-09-07T18:38:57.253577Z","end":"2026-09-07T18:38:57.590901Z","steps":["trace[1378939284] 'process raft request' (duration: 336.982776ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:38:57.590925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:38:57.251969Z","time spent":"338.863625ms","remote":"127.0.0.1:36130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T18:38:57.591171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:38:57.253567Z","time spent":"337.542981ms","remote":"127.0.0.1:36130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T18:38:57.591419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.114335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-07T18:38:57.591492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.748206ms","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":"warn","ts":"2026-09-07T18:38:57.59151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.501425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-09-07T18:38:57.591515Z","caller":"traceutil/trace.go:171","msg":"trace[542947971] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:8950; }","duration":"209.251758ms","start":"2026-09-07T18:38:57.382236Z","end":"2026-09-07T18:38:57.591488Z","steps":["trace[542947971] 'agreement among raft nodes before linearized reading' (duration: 209.053383ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:38:57.591556Z","caller":"traceutil/trace.go:171","msg":"trace[1568989630] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:8950; }","duration":"191.853399ms","start":"2026-09-07T18:38:57.399682Z","end":"2026-09-07T18:38:57.591535Z","steps":["trace[1568989630] 'agreement among raft nodes before linearized reading' (duration: 191.676805ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:38:57.591561Z","caller":"traceutil/trace.go:171","msg":"trace[985406340] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8950; }","duration":"107.577918ms","start":"2026-09-07T18:38:57.483968Z","end":"2026-09-07T18:38:57.591546Z","steps":["trace[985406340] 'agreement among raft nodes before linearized reading' (duration: 107.325301ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:38:57.591667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.473281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228533"} {"level":"info","ts":"2026-09-07T18:38:57.591731Z","caller":"traceutil/trace.go:171","msg":"trace[1479593343] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8950; }","duration":"339.580053ms","start":"2026-09-07T18:38:57.252136Z","end":"2026-09-07T18:38:57.591716Z","steps":["trace[1479593343] 'agreement among raft nodes before linearized reading' (duration: 338.863495ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:38:57.591768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:38:57.252126Z","time spent":"339.630765ms","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":26,"response size":228557,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-09-07T18:39:04.254171Z","caller":"traceutil/trace.go:171","msg":"trace[357812946] transaction","detail":"{read_only:false; response_revision:9027; number_of_response:1; }","duration":"123.159197ms","start":"2026-09-07T18:39:04.13099Z","end":"2026-09-07T18:39:04.254149Z","steps":["trace[357812946] 'process raft request' (duration: 123.028914ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:39:04.422126Z","caller":"traceutil/trace.go:171","msg":"trace[1099816224] transaction","detail":"{read_only:false; response_revision:9029; number_of_response:1; }","duration":"182.887922ms","start":"2026-09-07T18:39:04.239217Z","end":"2026-09-07T18:39:04.422105Z","steps":["trace[1099816224] 'process raft request' (duration: 182.79769ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:39:04.422147Z","caller":"traceutil/trace.go:171","msg":"trace[1957792611] transaction","detail":"{read_only:false; response_revision:9028; number_of_response:1; }","duration":"183.485829ms","start":"2026-09-07T18:39:04.23864Z","end":"2026-09-07T18:39:04.422126Z","steps":["trace[1957792611] 'process raft request' (duration: 182.4251ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:39:04.506219Z","caller":"traceutil/trace.go:171","msg":"trace[577871335] transaction","detail":"{read_only:false; response_revision:9030; number_of_response:1; }","duration":"112.169247ms","start":"2026-09-07T18:39:04.39402Z","end":"2026-09-07T18:39:04.50619Z","steps":["trace[577871335] 'process raft request' (duration: 112.030133ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:39:24.683864Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000254a.wal"} {"level":"info","ts":"2026-09-07T18:40:05.627016Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8572cc3a5351216d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-07T18:40:05.633393Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-07T18:40:05.633471Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-07T18:40:24.062315Z","caller":"traceutil/trace.go:171","msg":"trace[590999840] linearizableReadLoop","detail":"{readStateIndex:10137; appliedIndex:10136; }","duration":"163.241754ms","start":"2026-09-07T18:40:23.899044Z","end":"2026-09-07T18:40:24.062285Z","steps":["trace[590999840] 'read index received' (duration: 85.514455ms)","trace[590999840] 'applied index is now lower than readState.Index' (duration: 77.725709ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:40:24.062412Z","caller":"traceutil/trace.go:171","msg":"trace[410820021] transaction","detail":"{read_only:false; response_revision:9789; number_of_response:1; }","duration":"277.735873ms","start":"2026-09-07T18:40:23.784583Z","end":"2026-09-07T18:40:24.062319Z","steps":["trace[410820021] 'process raft request' (duration: 200.046024ms)","trace[410820021] 'compare' (duration: 77.436842ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:40:24.062483Z","caller":"traceutil/trace.go:171","msg":"trace[13564952] transaction","detail":"{read_only:false; response_revision:9790; number_of_response:1; }","duration":"153.688173ms","start":"2026-09-07T18:40:23.908772Z","end":"2026-09-07T18:40:24.06246Z","steps":["trace[13564952] 'process raft request' (duration: 153.468177ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:40:24.062564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.511271ms","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-09-07T18:40:24.062716Z","caller":"traceutil/trace.go:171","msg":"trace[1725618476] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9790; }","duration":"163.704226ms","start":"2026-09-07T18:40:23.898985Z","end":"2026-09-07T18:40:24.06269Z","steps":["trace[1725618476] 'agreement among raft nodes before linearized reading' (duration: 163.435369ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:40:24.217125Z","caller":"traceutil/trace.go:171","msg":"trace[1710690841] linearizableReadLoop","detail":"{readStateIndex:10139; appliedIndex:10138; }","duration":"126.588758ms","start":"2026-09-07T18:40:24.090466Z","end":"2026-09-07T18:40:24.217055Z","steps":["trace[1710690841] 'read index received' (duration: 92.608122ms)","trace[1710690841] 'applied index is now lower than readState.Index' (duration: 33.979096ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:40:24.217148Z","caller":"traceutil/trace.go:171","msg":"trace[693474638] transaction","detail":"{read_only:false; response_revision:9791; number_of_response:1; }","duration":"145.683032ms","start":"2026-09-07T18:40:24.071437Z","end":"2026-09-07T18:40:24.21712Z","steps":["trace[693474638] 'process raft request' (duration: 111.748647ms)","trace[693474638] 'compare' (duration: 33.682158ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:40:24.217455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.142122ms","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":"warn","ts":"2026-09-07T18:40:24.217455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.970978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-07T18:40:24.217513Z","caller":"traceutil/trace.go:171","msg":"trace[787470981] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9791; }","duration":"119.200974ms","start":"2026-09-07T18:40:24.098291Z","end":"2026-09-07T18:40:24.217492Z","steps":["trace[787470981] 'agreement among raft nodes before linearized reading' (duration: 118.984508ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:40:24.217517Z","caller":"traceutil/trace.go:171","msg":"trace[154787926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9791; }","duration":"127.05024ms","start":"2026-09-07T18:40:24.090452Z","end":"2026-09-07T18:40:24.217503Z","steps":["trace[154787926] 'agreement among raft nodes before linearized reading' (duration: 126.772603ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:41:36.942272Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7871} {"level":"info","ts":"2026-09-07T18:41:37.032081Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7871,"took":"87.90827ms","hash":2787543326,"current-db-size-bytes":50479104,"current-db-size":"50 MB","current-db-size-in-use-bytes":26025984,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-07T18:41:37.032152Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2787543326,"revision":7871,"compact-revision":5141} {"level":"info","ts":"2026-09-07T18:42:47.110553Z","caller":"traceutil/trace.go:171","msg":"trace[1527992649] linearizableReadLoop","detail":"{readStateIndex:11248; appliedIndex:11247; }","duration":"185.195867ms","start":"2026-09-07T18:42:46.925323Z","end":"2026-09-07T18:42:47.110519Z","steps":["trace[1527992649] 'read index received' (duration: 184.862829ms)","trace[1527992649] 'applied index is now lower than readState.Index' (duration: 331.738µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:42:47.110633Z","caller":"traceutil/trace.go:171","msg":"trace[226268485] transaction","detail":"{read_only:false; response_revision:10863; number_of_response:1; }","duration":"187.607761ms","start":"2026-09-07T18:42:46.92297Z","end":"2026-09-07T18:42:47.110578Z","steps":["trace[226268485] 'process raft request' (duration: 187.290533ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:42:47.110956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.849122ms","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":"warn","ts":"2026-09-07T18:42:47.110969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.584927ms","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-09-07T18:42:47.11104Z","caller":"traceutil/trace.go:171","msg":"trace[1465794394] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:10863; }","duration":"147.988445ms","start":"2026-09-07T18:42:46.963031Z","end":"2026-09-07T18:42:47.111019Z","steps":["trace[1465794394] 'agreement among raft nodes before linearized reading' (duration: 147.759729ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:42:47.111084Z","caller":"traceutil/trace.go:171","msg":"trace[1749715736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10863; }","duration":"185.759372ms","start":"2026-09-07T18:42:46.925299Z","end":"2026-09-07T18:42:47.111059Z","steps":["trace[1749715736] 'agreement among raft nodes before linearized reading' (duration: 185.395352ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:42:47.111448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.952998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/staffeln-db-init-228pv\" ","response":"range_response_count:1 size:7350"} {"level":"info","ts":"2026-09-07T18:42:47.111518Z","caller":"traceutil/trace.go:171","msg":"trace[676662932] range","detail":"{range_begin:/registry/pods/openstack/staffeln-db-init-228pv; range_end:; response_count:1; response_revision:10863; }","duration":"169.129902ms","start":"2026-09-07T18:42:46.942373Z","end":"2026-09-07T18:42:47.111503Z","steps":["trace[676662932] 'agreement among raft nodes before linearized reading' (duration: 168.387803ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:46:36.95067Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10291} {"level":"info","ts":"2026-09-07T18:46:37.045756Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10291,"took":"92.442088ms","hash":2078679642,"current-db-size-bytes":50479104,"current-db-size":"50 MB","current-db-size-in-use-bytes":28098560,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-07T18:46:37.045845Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2078679642,"revision":10291,"compact-revision":7871} {"level":"info","ts":"2026-09-07T18:50:35.018908Z","caller":"traceutil/trace.go:171","msg":"trace[1380462274] transaction","detail":"{read_only:false; response_revision:14233; number_of_response:1; }","duration":"309.406619ms","start":"2026-09-07T18:50:34.709474Z","end":"2026-09-07T18:50:35.01888Z","steps":["trace[1380462274] 'process raft request' (duration: 308.958838ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:50:35.01906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:50:34.709449Z","time spent":"309.534082ms","remote":"127.0.0.1:36130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T18:50:35.354868Z","caller":"traceutil/trace.go:171","msg":"trace[1782111146] linearizableReadLoop","detail":"{readStateIndex:14730; appliedIndex:14728; }","duration":"287.923939ms","start":"2026-09-07T18:50:35.066917Z","end":"2026-09-07T18:50:35.354841Z","steps":["trace[1782111146] 'read index received' (duration: 203.818188ms)","trace[1782111146] 'applied index is now lower than readState.Index' (duration: 84.104601ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:50:35.355168Z","caller":"traceutil/trace.go:171","msg":"trace[895638523] transaction","detail":"{read_only:false; response_revision:14234; number_of_response:1; }","duration":"332.335856ms","start":"2026-09-07T18:50:35.022813Z","end":"2026-09-07T18:50:35.355149Z","steps":["trace[895638523] 'process raft request' (duration: 247.895946ms)","trace[895638523] 'compare' (duration: 83.191377ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:50:35.355292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:50:35.02279Z","time spent":"332.426788ms","remote":"127.0.0.1:36130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T18:50:35.355513Z","caller":"traceutil/trace.go:171","msg":"trace[2106213552] transaction","detail":"{read_only:false; response_revision:14235; number_of_response:1; }","duration":"289.529751ms","start":"2026-09-07T18:50:35.065968Z","end":"2026-09-07T18:50:35.355498Z","steps":["trace[2106213552] 'process raft request' (duration: 288.74009ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:50:35.355886Z","caller":"traceutil/trace.go:171","msg":"trace[1739664936] transaction","detail":"{read_only:false; response_revision:14236; number_of_response:1; }","duration":"256.530323ms","start":"2026-09-07T18:50:35.099293Z","end":"2026-09-07T18:50:35.355823Z","steps":["trace[1739664936] 'process raft request' (duration: 255.500945ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:50:35.35602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.10395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:50:35.356076Z","caller":"traceutil/trace.go:171","msg":"trace[1059379065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14236; }","duration":"289.200802ms","start":"2026-09-07T18:50:35.066862Z","end":"2026-09-07T18:50:35.356063Z","steps":["trace[1059379065] 'agreement among raft nodes before linearized reading' (duration: 289.1226ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:51:36.957561Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12662} {"level":"info","ts":"2026-09-07T18:51:37.045323Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12662,"took":"84.947233ms","hash":2376737870,"current-db-size-bytes":50479104,"current-db-size":"50 MB","current-db-size-in-use-bytes":30412800,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-07T18:51:37.045401Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2376737870,"revision":12662,"compact-revision":10291} {"level":"warn","ts":"2026-09-07T18:51:47.90311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.348664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:51:47.903206Z","caller":"traceutil/trace.go:171","msg":"trace[911257948] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:15164; }","duration":"133.481837ms","start":"2026-09-07T18:51:47.769705Z","end":"2026-09-07T18:51:47.903187Z","steps":["trace[911257948] 'count revisions from in-memory index tree' (duration: 133.294643ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:51:47.903344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.482275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-09-07T18:51:47.903383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.932551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:51:47.903424Z","caller":"traceutil/trace.go:171","msg":"trace[1964543208] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15164; }","duration":"141.601489ms","start":"2026-09-07T18:51:47.761805Z","end":"2026-09-07T18:51:47.903407Z","steps":["trace[1964543208] 'range keys from in-memory index tree' (duration: 141.317531ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:51:47.903435Z","caller":"traceutil/trace.go:171","msg":"trace[618908242] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15164; }","duration":"141.004963ms","start":"2026-09-07T18:51:47.762417Z","end":"2026-09-07T18:51:47.903422Z","steps":["trace[618908242] 'range keys from in-memory index tree' (duration: 140.88003ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:51:47.903492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.589782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-09-07T18:51:47.903527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.687941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:681"} {"level":"info","ts":"2026-09-07T18:51:47.903562Z","caller":"traceutil/trace.go:171","msg":"trace[2091982085] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15164; }","duration":"140.674675ms","start":"2026-09-07T18:51:47.76287Z","end":"2026-09-07T18:51:47.903545Z","steps":["trace[2091982085] 'range keys from in-memory index tree' (duration: 140.484309ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:51:47.903634Z","caller":"traceutil/trace.go:171","msg":"trace[1456606348] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15164; }","duration":"141.830214ms","start":"2026-09-07T18:51:47.761785Z","end":"2026-09-07T18:51:47.903616Z","steps":["trace[1456606348] 'range keys from in-memory index tree' (duration: 141.486986ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:51:47.903667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.905247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T18:51:47.90374Z","caller":"traceutil/trace.go:171","msg":"trace[989704355] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15164; }","duration":"142.395828ms","start":"2026-09-07T18:51:47.761328Z","end":"2026-09-07T18:51:47.903724Z","steps":["trace[989704355] 'range keys from in-memory index tree' (duration: 141.770783ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:52:15.823648Z","caller":"traceutil/trace.go:171","msg":"trace[1934592869] transaction","detail":"{read_only:false; response_revision:15309; number_of_response:1; }","duration":"107.849879ms","start":"2026-09-07T18:52:15.715774Z","end":"2026-09-07T18:52:15.823623Z","steps":["trace[1934592869] 'process raft request' (duration: 105.374264ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:52:22.604404Z","caller":"traceutil/trace.go:171","msg":"trace[26618499] linearizableReadLoop","detail":"{readStateIndex:15876; appliedIndex:15875; }","duration":"241.557241ms","start":"2026-09-07T18:52:22.362823Z","end":"2026-09-07T18:52:22.60438Z","steps":["trace[26618499] 'read index received' (duration: 171.460945ms)","trace[26618499] 'applied index is now lower than readState.Index' (duration: 70.095306ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T18:52:22.604512Z","caller":"traceutil/trace.go:171","msg":"trace[1723247654] transaction","detail":"{read_only:false; response_revision:15344; number_of_response:1; }","duration":"258.238115ms","start":"2026-09-07T18:52:22.346226Z","end":"2026-09-07T18:52:22.604464Z","steps":["trace[1723247654] 'process raft request' (duration: 188.090808ms)","trace[1723247654] 'compare' (duration: 69.940302ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:52:22.604731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.882119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-qmbz8\" ","response":"range_response_count:1 size:22132"} {"level":"info","ts":"2026-09-07T18:52:22.604788Z","caller":"traceutil/trace.go:171","msg":"trace[2145473825] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-qmbz8; range_end:; response_count:1; response_revision:15344; }","duration":"241.982942ms","start":"2026-09-07T18:52:22.362786Z","end":"2026-09-07T18:52:22.604769Z","steps":["trace[2145473825] 'agreement among raft nodes before linearized reading' (duration: 241.746696ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:52:22.604867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.117068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pp5bg\" ","response":"range_response_count:1 size:6530"} {"level":"warn","ts":"2026-09-07T18:52:22.604889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.848948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-qmbz8\" ","response":"range_response_count:1 size:22132"} {"level":"info","ts":"2026-09-07T18:52:22.604916Z","caller":"traceutil/trace.go:171","msg":"trace[280791960] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pp5bg; range_end:; response_count:1; response_revision:15344; }","duration":"178.205601ms","start":"2026-09-07T18:52:22.426696Z","end":"2026-09-07T18:52:22.604902Z","steps":["trace[280791960] 'agreement among raft nodes before linearized reading' (duration: 178.054667ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:52:22.604967Z","caller":"traceutil/trace.go:171","msg":"trace[1363530463] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-qmbz8; range_end:; response_count:1; response_revision:15344; }","duration":"206.956731ms","start":"2026-09-07T18:52:22.397987Z","end":"2026-09-07T18:52:22.604944Z","steps":["trace[1363530463] 'agreement among raft nodes before linearized reading' (duration: 206.746735ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:52:22.604967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.080701ms","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":"warn","ts":"2026-09-07T18:52:22.604968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.585608ms","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-09-07T18:52:22.605045Z","caller":"traceutil/trace.go:171","msg":"trace[1956316633] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:15344; }","duration":"200.186214ms","start":"2026-09-07T18:52:22.404823Z","end":"2026-09-07T18:52:22.605009Z","steps":["trace[1956316633] 'agreement among raft nodes before linearized reading' (duration: 199.961008ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:52:22.605041Z","caller":"traceutil/trace.go:171","msg":"trace[1419808977] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15344; }","duration":"141.6817ms","start":"2026-09-07T18:52:22.463339Z","end":"2026-09-07T18:52:22.605021Z","steps":["trace[1419808977] 'agreement among raft nodes before linearized reading' (duration: 141.504605ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:56:36.968746Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15083} {"level":"info","ts":"2026-09-07T18:56:37.075589Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15083,"took":"102.248042ms","hash":3453465399,"current-db-size-bytes":50479104,"current-db-size":"50 MB","current-db-size-in-use-bytes":31399936,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-07T18:56:37.075685Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3453465399,"revision":15083,"compact-revision":12662} {"level":"info","ts":"2026-09-07T18:57:45.080448Z","caller":"traceutil/trace.go:171","msg":"trace[645972047] transaction","detail":"{read_only:false; response_revision:17793; number_of_response:1; }","duration":"118.223479ms","start":"2026-09-07T18:57:44.962197Z","end":"2026-09-07T18:57:45.080421Z","steps":["trace[645972047] 'process raft request' (duration: 118.047024ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:57:50.904856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.669903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-z86gq\" ","response":"range_response_count:1 size:20763"} {"level":"info","ts":"2026-09-07T18:57:50.904966Z","caller":"traceutil/trace.go:171","msg":"trace[1402412416] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-z86gq; range_end:; response_count:1; response_revision:17825; }","duration":"291.856859ms","start":"2026-09-07T18:57:50.613086Z","end":"2026-09-07T18:57:50.904943Z","steps":["trace[1402412416] 'range keys from in-memory index tree' (duration: 291.412407ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:57:51.191482Z","caller":"traceutil/trace.go:171","msg":"trace[1572258922] linearizableReadLoop","detail":"{readStateIndex:18439; appliedIndex:18438; }","duration":"265.769995ms","start":"2026-09-07T18:57:50.925681Z","end":"2026-09-07T18:57:51.191451Z","steps":["trace[1572258922] 'read index received' (duration: 265.604081ms)","trace[1572258922] 'applied index is now lower than readState.Index' (duration: 164.494µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:57:51.191754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.047233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-09-07T18:57:51.191696Z","caller":"traceutil/trace.go:171","msg":"trace[2144971533] transaction","detail":"{read_only:false; response_revision:17826; number_of_response:1; }","duration":"280.024679ms","start":"2026-09-07T18:57:50.911528Z","end":"2026-09-07T18:57:51.191552Z","steps":["trace[2144971533] 'process raft request' (duration: 279.753192ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:57:51.191796Z","caller":"traceutil/trace.go:171","msg":"trace[380247151] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17826; }","duration":"266.155356ms","start":"2026-09-07T18:57:50.925629Z","end":"2026-09-07T18:57:51.191785Z","steps":["trace[380247151] 'agreement among raft nodes before linearized reading' (duration: 265.948641ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:57:51.375046Z","caller":"traceutil/trace.go:171","msg":"trace[279738492] transaction","detail":"{read_only:false; response_revision:17827; number_of_response:1; }","duration":"446.930087ms","start":"2026-09-07T18:57:50.928086Z","end":"2026-09-07T18:57:51.375016Z","steps":["trace[279738492] 'process raft request' (duration: 446.67997ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:57:51.37522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:57:50.928072Z","time spent":"447.060431ms","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20878,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T18:57:51.375371Z","caller":"traceutil/trace.go:171","msg":"trace[1200395327] transaction","detail":"{read_only:false; response_revision:17828; number_of_response:1; }","duration":"272.338578ms","start":"2026-09-07T18:57:51.103001Z","end":"2026-09-07T18:57:51.37534Z","steps":["trace[1200395327] 'process raft request' (duration: 271.953247ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T18:57:51.375919Z","caller":"traceutil/trace.go:171","msg":"trace[1800973141] linearizableReadLoop","detail":"{readStateIndex:18442; appliedIndex:18439; }","duration":"184.348795ms","start":"2026-09-07T18:57:51.191554Z","end":"2026-09-07T18:57:51.375903Z","steps":["trace[1800973141] 'read index received' (duration: 183.177294ms)","trace[1800973141] 'applied index is now lower than readState.Index' (duration: 1.16678ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T18:57:51.376107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.808069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T18:57:51.376147Z","caller":"traceutil/trace.go:171","msg":"trace[1129712084] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17829; }","duration":"306.898352ms","start":"2026-09-07T18:57:51.069238Z","end":"2026-09-07T18:57:51.376137Z","steps":["trace[1129712084] 'agreement among raft nodes before linearized reading' (duration: 306.774368ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:57:51.376174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.162131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-qmbz8\" ","response":"range_response_count:1 size:22133"} {"level":"info","ts":"2026-09-07T18:57:51.376228Z","caller":"traceutil/trace.go:171","msg":"trace[421577964] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-qmbz8; range_end:; response_count:1; response_revision:17829; }","duration":"215.311205ms","start":"2026-09-07T18:57:51.160904Z","end":"2026-09-07T18:57:51.376216Z","steps":["trace[421577964] 'agreement among raft nodes before linearized reading' (duration: 215.100319ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:57:51.376177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:57:51.069217Z","time spent":"306.951863ms","remote":"127.0.0.1:35884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-07T18:57:51.377107Z","caller":"traceutil/trace.go:171","msg":"trace[208057405] transaction","detail":"{read_only:false; response_revision:17829; number_of_response:1; }","duration":"273.96786ms","start":"2026-09-07T18:57:51.103107Z","end":"2026-09-07T18:57:51.377075Z","steps":["trace[208057405] 'process raft request' (duration: 272.07849ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:57:51.377587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.936702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-09-07T18:57:51.377635Z","caller":"traceutil/trace.go:171","msg":"trace[609781836] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17829; }","duration":"182.008743ms","start":"2026-09-07T18:57:51.195615Z","end":"2026-09-07T18:57:51.377624Z","steps":["trace[609781836] 'agreement among raft nodes before linearized reading' (duration: 181.836019ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:57:51.377869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.29652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T18:57:51.378149Z","caller":"traceutil/trace.go:171","msg":"trace[1057204559] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:17829; }","duration":"360.842023ms","start":"2026-09-07T18:57:51.017275Z","end":"2026-09-07T18:57:51.378117Z","steps":["trace[1057204559] 'agreement among raft nodes before linearized reading' (duration: 360.209247ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T18:57:51.378268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:57:51.01725Z","time spent":"360.973347ms","remote":"127.0.0.1:56234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true "} {"level":"info","ts":"2026-09-07T18:58:01.931014Z","caller":"traceutil/trace.go:171","msg":"trace[1057556177] transaction","detail":"{read_only:false; response_revision:18009; number_of_response:1; }","duration":"103.496278ms","start":"2026-09-07T18:58:01.827479Z","end":"2026-09-07T18:58:01.930975Z","steps":["trace[1057556177] 'process raft request' (duration: 90.773546ms)","trace[1057556177] 'compare' (duration: 12.573308ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:00:37.498027Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8572cc3a5351216d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-07T19:00:37.50591Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-07T19:00:37.506069Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-07T19:01:36.975353Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17310} {"level":"info","ts":"2026-09-07T19:01:37.078902Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17310,"took":"99.943035ms","hash":4114156558,"current-db-size-bytes":50909184,"current-db-size":"51 MB","current-db-size-in-use-bytes":35057664,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-09-07T19:01:37.078972Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4114156558,"revision":17310,"compact-revision":15083} {"level":"info","ts":"2026-09-07T19:03:24.020254Z","caller":"traceutil/trace.go:171","msg":"trace[814621087] linearizableReadLoop","detail":"{readStateIndex:21197; appliedIndex:21196; }","duration":"121.12599ms","start":"2026-09-07T19:03:23.899105Z","end":"2026-09-07T19:03:24.020231Z","steps":["trace[814621087] 'read index received' (duration: 120.931495ms)","trace[814621087] 'applied index is now lower than readState.Index' (duration: 193.525µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:03:24.020341Z","caller":"traceutil/trace.go:171","msg":"trace[1910572076] transaction","detail":"{read_only:false; response_revision:20500; number_of_response:1; }","duration":"159.720671ms","start":"2026-09-07T19:03:23.860597Z","end":"2026-09-07T19:03:24.020318Z","steps":["trace[1910572076] 'process raft request' (duration: 159.483134ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:03:24.020382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.260494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:03:24.020442Z","caller":"traceutil/trace.go:171","msg":"trace[1149941516] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:20500; }","duration":"121.362937ms","start":"2026-09-07T19:03:23.899068Z","end":"2026-09-07T19:03:24.020431Z","steps":["trace[1149941516] 'agreement among raft nodes before linearized reading' (duration: 121.256974ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:03:24.387733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.494096ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T19:03:24.387812Z","caller":"traceutil/trace.go:171","msg":"trace[1212331850] linearizableReadLoop","detail":"{readStateIndex:21198; appliedIndex:21197; }","duration":"322.526782ms","start":"2026-09-07T19:03:24.065275Z","end":"2026-09-07T19:03:24.387801Z","steps":["trace[1212331850] 'read index received' (duration: 50.835911ms)","trace[1212331850] 'applied index is now lower than readState.Index' (duration: 271.690271ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:03:24.387869Z","caller":"traceutil/trace.go:171","msg":"trace[654932583] transaction","detail":"{read_only:false; response_revision:20501; number_of_response:1; }","duration":"344.319952ms","start":"2026-09-07T19:03:24.043543Z","end":"2026-09-07T19:03:24.387863Z","steps":["trace[654932583] 'process raft request' (duration: 72.614661ms)","trace[654932583] 'compare' (duration: 271.398103ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:03:24.387904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:03:24.043529Z","time spent":"344.359272ms","remote":"127.0.0.1:36130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T19:03:24.387952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.039496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-07T19:03:24.388005Z","caller":"traceutil/trace.go:171","msg":"trace[721112931] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:20501; }","duration":"273.130979ms","start":"2026-09-07T19:03:24.114859Z","end":"2026-09-07T19:03:24.38799Z","steps":["trace[721112931] 'agreement among raft nodes before linearized reading' (duration: 273.033277ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:03:24.388207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.590838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-07T19:03:24.388235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.942093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T19:03:24.388261Z","caller":"traceutil/trace.go:171","msg":"trace[257938444] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:20501; }","duration":"156.678691ms","start":"2026-09-07T19:03:24.231571Z","end":"2026-09-07T19:03:24.38825Z","steps":["trace[257938444] 'agreement among raft nodes before linearized reading' (duration: 156.583458ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:03:24.388295Z","caller":"traceutil/trace.go:171","msg":"trace[959611733] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20501; }","duration":"323.039456ms","start":"2026-09-07T19:03:24.065239Z","end":"2026-09-07T19:03:24.388279Z","steps":["trace[959611733] 'agreement among raft nodes before linearized reading' (duration: 322.920243ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:03:24.388317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:03:24.065224Z","time spent":"323.089217ms","remote":"127.0.0.1:35886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-07T19:03:34.834934Z","caller":"traceutil/trace.go:171","msg":"trace[1891232737] transaction","detail":"{read_only:false; response_revision:20557; number_of_response:1; }","duration":"107.735089ms","start":"2026-09-07T19:03:34.727175Z","end":"2026-09-07T19:03:34.83491Z","steps":["trace[1891232737] 'process raft request' (duration: 107.465812ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:03:34.835818Z","caller":"traceutil/trace.go:171","msg":"trace[1526024181] linearizableReadLoop","detail":"{readStateIndex:21256; appliedIndex:21256; }","duration":"104.620779ms","start":"2026-09-07T19:03:34.731167Z","end":"2026-09-07T19:03:34.835788Z","steps":["trace[1526024181] 'read index received' (duration: 104.439034ms)","trace[1526024181] 'applied index is now lower than readState.Index' (duration: 178.964µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:03:34.836184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.992648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-09-07T19:03:34.836279Z","caller":"traceutil/trace.go:171","msg":"trace[188481872] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:20557; }","duration":"105.115722ms","start":"2026-09-07T19:03:34.731141Z","end":"2026-09-07T19:03:34.836257Z","steps":["trace[188481872] 'agreement among raft nodes before linearized reading' (duration: 104.823154ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:03:40.748733Z","caller":"traceutil/trace.go:171","msg":"trace[1783222729] linearizableReadLoop","detail":"{readStateIndex:21282; appliedIndex:21281; }","duration":"226.5776ms","start":"2026-09-07T19:03:40.522102Z","end":"2026-09-07T19:03:40.74868Z","steps":["trace[1783222729] 'read index received' (duration: 226.519058ms)","trace[1783222729] 'applied index is now lower than readState.Index' (duration: 57.332µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:03:40.748847Z","caller":"traceutil/trace.go:171","msg":"trace[589321544] transaction","detail":"{read_only:false; response_revision:20581; number_of_response:1; }","duration":"264.932145ms","start":"2026-09-07T19:03:40.483854Z","end":"2026-09-07T19:03:40.748786Z","steps":["trace[589321544] 'process raft request' (duration: 264.678748ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:03:40.748977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.759162ms","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-09-07T19:03:40.749016Z","caller":"traceutil/trace.go:171","msg":"trace[1038636322] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20581; }","duration":"266.849475ms","start":"2026-09-07T19:03:40.482156Z","end":"2026-09-07T19:03:40.749005Z","steps":["trace[1038636322] 'agreement among raft nodes before linearized reading' (duration: 266.648099ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:03:40.749016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.732615ms","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-09-07T19:03:40.749076Z","caller":"traceutil/trace.go:171","msg":"trace[490859976] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20581; }","duration":"252.796437ms","start":"2026-09-07T19:03:40.496262Z","end":"2026-09-07T19:03:40.749058Z","steps":["trace[490859976] 'agreement among raft nodes before linearized reading' (duration: 252.568231ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:03:41.115306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.100934ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T19:03:41.115566Z","caller":"traceutil/trace.go:171","msg":"trace[791537373] transaction","detail":"{read_only:false; response_revision:20582; number_of_response:1; }","duration":"592.396225ms","start":"2026-09-07T19:03:40.523141Z","end":"2026-09-07T19:03:41.115537Z","steps":["trace[791537373] 'process raft request' (duration: 393.957191ms)","trace[791537373] 'compare' (duration: 197.951362ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:03:41.115625Z","caller":"traceutil/trace.go:171","msg":"trace[255339705] transaction","detail":"{read_only:false; response_revision:20584; number_of_response:1; }","duration":"361.956364ms","start":"2026-09-07T19:03:40.753648Z","end":"2026-09-07T19:03:41.115604Z","steps":["trace[255339705] 'process raft request' (duration: 361.851231ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:03:41.115674Z","caller":"traceutil/trace.go:171","msg":"trace[2052355401] transaction","detail":"{read_only:false; response_revision:20583; number_of_response:1; }","duration":"589.287213ms","start":"2026-09-07T19:03:40.526356Z","end":"2026-09-07T19:03:41.115643Z","steps":["trace[2052355401] 'process raft request' (duration: 589.080338ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:03:41.115706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:03:40.523118Z","time spent":"592.503328ms","remote":"127.0.0.1:35900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T19:03:41.115742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:03:40.753625Z","time spent":"362.049206ms","remote":"127.0.0.1:36130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T19:03:41.115834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:03:40.526342Z","time spent":"589.412327ms","remote":"127.0.0.1:36130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T19:03:41.116039Z","caller":"traceutil/trace.go:171","msg":"trace[1518074289] linearizableReadLoop","detail":"{readStateIndex:21288; appliedIndex:21282; }","duration":"198.822584ms","start":"2026-09-07T19:03:40.917196Z","end":"2026-09-07T19:03:41.116018Z","steps":["trace[1518074289] 'read index received' (duration: 16.02208ms)","trace[1518074289] 'applied index is now lower than readState.Index' (duration: 182.799314ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:03:41.116027Z","caller":"traceutil/trace.go:171","msg":"trace[2084795500] transaction","detail":"{read_only:false; response_revision:20585; number_of_response:1; }","duration":"360.049413ms","start":"2026-09-07T19:03:40.755936Z","end":"2026-09-07T19:03:41.115986Z","steps":["trace[2084795500] 'process raft request' (duration: 359.621863ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:03:41.11609Z","caller":"traceutil/trace.go:171","msg":"trace[1520024664] transaction","detail":"{read_only:false; response_revision:20587; number_of_response:1; }","duration":"260.139949ms","start":"2026-09-07T19:03:40.855936Z","end":"2026-09-07T19:03:41.116076Z","steps":["trace[1520024664] 'process raft request' (duration: 260.031876ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:03:41.116108Z","caller":"traceutil/trace.go:171","msg":"trace[870898259] transaction","detail":"{read_only:false; response_revision:20586; number_of_response:1; }","duration":"263.755202ms","start":"2026-09-07T19:03:40.852309Z","end":"2026-09-07T19:03:41.116064Z","steps":["trace[870898259] 'process raft request' (duration: 263.553727ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:03:41.116234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.02415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-07T19:03:41.116225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:03:40.755914Z","time spent":"360.216777ms","remote":"127.0.0.1:36130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T19:03:41.116264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.543739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-07T19:03:41.11628Z","caller":"traceutil/trace.go:171","msg":"trace[416471790] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:20587; }","duration":"199.107041ms","start":"2026-09-07T19:03:40.917161Z","end":"2026-09-07T19:03:41.116268Z","steps":["trace[416471790] 'agreement among raft nodes before linearized reading' (duration: 198.992448ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:03:41.116325Z","caller":"traceutil/trace.go:171","msg":"trace[366342389] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:20587; }","duration":"179.626881ms","start":"2026-09-07T19:03:40.936679Z","end":"2026-09-07T19:03:41.116306Z","steps":["trace[366342389] 'agreement among raft nodes before linearized reading' (duration: 179.522918ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:03:41.388558Z","caller":"traceutil/trace.go:171","msg":"trace[1265309493] linearizableReadLoop","detail":"{readStateIndex:21289; appliedIndex:21288; }","duration":"258.988238ms","start":"2026-09-07T19:03:41.129543Z","end":"2026-09-07T19:03:41.388532Z","steps":["trace[1265309493] 'read index received' (duration: 96.452µs)","trace[1265309493] 'applied index is now lower than readState.Index' (duration: 258.890806ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T19:03:41.388646Z","caller":"traceutil/trace.go:171","msg":"trace[391240271] transaction","detail":"{read_only:false; response_revision:20588; number_of_response:1; }","duration":"259.092571ms","start":"2026-09-07T19:03:41.129511Z","end":"2026-09-07T19:03:41.388604Z","steps":["trace[391240271] 'process raft request' (duration: 176.029137ms)","trace[391240271] 'compare' (duration: 82.871869ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T19:03:41.388795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.235645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-09-07T19:03:41.388831Z","caller":"traceutil/trace.go:171","msg":"trace[2070452517] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:20588; }","duration":"259.329547ms","start":"2026-09-07T19:03:41.129492Z","end":"2026-09-07T19:03:41.388822Z","steps":["trace[2070452517] 'agreement among raft nodes before linearized reading' (duration: 259.132502ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:06:36.983027Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19798} {"level":"info","ts":"2026-09-07T19:06:37.099474Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19798,"took":"112.988767ms","hash":2370065987,"current-db-size-bytes":51773440,"current-db-size":"52 MB","current-db-size-in-use-bytes":30740480,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-07T19:06:37.099546Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2370065987,"revision":19798,"compact-revision":17310} {"level":"warn","ts":"2026-09-07T19:07:25.154202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.396298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-09-07T19:07:25.154297Z","caller":"traceutil/trace.go:171","msg":"trace[1630658832] range","detail":"{range_begin:/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi; range_end:; response_count:1; response_revision:23026; }","duration":"110.557073ms","start":"2026-09-07T19:07:25.043721Z","end":"2026-09-07T19:07:25.154278Z","steps":["trace[1630658832] 'range keys from in-memory index tree' (duration: 110.240545ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:07:25.154415Z","caller":"traceutil/trace.go:171","msg":"trace[876706763] transaction","detail":"{read_only:false; response_revision:23027; number_of_response:1; }","duration":"103.299623ms","start":"2026-09-07T19:07:25.051088Z","end":"2026-09-07T19:07:25.154388Z","steps":["trace[876706763] 'process raft request' (duration: 97.734948ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:07:25.499509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.002458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-09-07T19:07:25.499602Z","caller":"traceutil/trace.go:171","msg":"trace[457347511] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23027; }","duration":"207.146612ms","start":"2026-09-07T19:07:25.292434Z","end":"2026-09-07T19:07:25.49958Z","steps":["trace[457347511] 'range keys from in-memory index tree' (duration: 206.795572ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T19:07:25.499835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.880845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-07T19:07:25.499902Z","caller":"traceutil/trace.go:171","msg":"trace[1991258607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23027; }","duration":"172.978428ms","start":"2026-09-07T19:07:25.326906Z","end":"2026-09-07T19:07:25.499885Z","steps":["trace[1991258607] 'range keys from in-memory index tree' (duration: 172.386712ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T19:11:36.99637Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22132} {"level":"info","ts":"2026-09-07T19:11:37.131351Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22132,"took":"129.342724ms","hash":3221298471,"current-db-size-bytes":52195328,"current-db-size":"52 MB","current-db-size-in-use-bytes":42467328,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-07T19:11:37.131447Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3221298471,"revision":22132,"compact-revision":19798} {"level":"info","ts":"2026-09-07T19:16:37.004997Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25213} {"level":"info","ts":"2026-09-07T19:16:37.170588Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25213,"took":"161.025293ms","hash":2791836459,"current-db-size-bytes":52682752,"current-db-size":"53 MB","current-db-size-in-use-bytes":29851648,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-07T19:16:37.17066Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2791836459,"revision":25213,"compact-revision":22132}