{"level":"warn","ts":"2026-05-29T02:07:42.964411Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T02:07:42.964505Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.157:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.157:2380","--initial-cluster=instance=https://199.204.45.157:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.157:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.157:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-29T02:07:42.964566Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T02:07:42.964578Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-05-29T02:07:42.964595Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-29T02:07:42.965217Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"]} {"level":"info","ts":"2026-05-29T02:07:42.96529Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.157:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-29T02:07:42.9689Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.407348ms"} {"level":"info","ts":"2026-05-29T02:07:42.975441Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f170c75a1d97cc","cluster-id":"44a3176ec85feb9"} {"level":"info","ts":"2026-05-29T02:07:42.975527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=()"} {"level":"info","ts":"2026-05-29T02:07:42.97555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 0"} {"level":"info","ts":"2026-05-29T02:07:42.975564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f170c75a1d97cc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-29T02:07:42.975577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 1"} {"level":"info","ts":"2026-05-29T02:07:42.975617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"warn","ts":"2026-05-29T02:07:42.979946Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-29T02:07:42.981221Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-29T02:07:42.982269Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-29T02:07:42.984436Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f170c75a1d97cc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-29T02:07:42.984571Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f170c75a1d97cc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-29T02:07:42.984724Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-29T02:07:42.984828Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-29T02:07:42.984846Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-29T02:07:42.986908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"info","ts":"2026-05-29T02:07:42.987048Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","added-peer-id":"5f170c75a1d97cc","added-peer-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-05-29T02:07:42.991524Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-29T02:07:42.991671Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-05-29T02:07:42.99172Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-05-29T02:07:42.991945Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f170c75a1d97cc","initial-advertise-peer-urls":["https://199.204.45.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-29T02:07:42.991977Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-29T02:07:43.676254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc is starting a new election at term 1"} {"level":"info","ts":"2026-05-29T02:07:43.676327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became pre-candidate at term 1"} {"level":"info","ts":"2026-05-29T02:07:43.676365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgPreVoteResp from 5f170c75a1d97cc at term 1"} {"level":"info","ts":"2026-05-29T02:07:43.676384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became candidate at term 2"} {"level":"info","ts":"2026-05-29T02:07:43.676391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgVoteResp from 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-05-29T02:07:43.676403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became leader at term 2"} {"level":"info","ts":"2026-05-29T02:07:43.676415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f170c75a1d97cc elected leader 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-05-29T02:07:43.677595Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f170c75a1d97cc","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.157:2379]}","request-path":"/0/members/5f170c75a1d97cc/attributes","cluster-id":"44a3176ec85feb9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-29T02:07:43.677598Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T02:07:43.677618Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T02:07:43.677756Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T02:07:43.67792Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-29T02:07:43.67799Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-29T02:07:43.678831Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T02:07:43.678929Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T02:07:43.678963Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T02:07:43.680603Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-29T02:07:43.681264Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.157:2379"} {"level":"warn","ts":"2026-05-29T02:08:22.45512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.610094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/tlsroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:65214"} {"level":"info","ts":"2026-05-29T02:08:22.455293Z","caller":"traceutil/trace.go:171","msg":"trace[1812887860] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/tlsroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:563; }","duration":"242.84195ms","start":"2026-05-29T02:08:22.212428Z","end":"2026-05-29T02:08:22.455269Z","steps":["trace[1812887860] 'range keys from in-memory index tree' (duration: 242.418001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:22.455972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.264678ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T02:08:22.456113Z","caller":"traceutil/trace.go:171","msg":"trace[1909199229] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:587; }","duration":"195.035561ms","start":"2026-05-29T02:08:22.261058Z","end":"2026-05-29T02:08:22.456094Z","steps":["trace[1909199229] 'read index received' (duration: 47.841µs)","trace[1909199229] 'applied index is now lower than readState.Index' (duration: 194.9859ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:08:22.456197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.138033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:08:22.456233Z","caller":"traceutil/trace.go:171","msg":"trace[278211194] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:564; }","duration":"195.214295ms","start":"2026-05-29T02:08:22.261008Z","end":"2026-05-29T02:08:22.456223Z","steps":["trace[278211194] 'agreement among raft nodes before linearized reading' (duration: 195.146274ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:08:22.456178Z","caller":"traceutil/trace.go:171","msg":"trace[1683359016] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"238.746338ms","start":"2026-05-29T02:08:22.217407Z","end":"2026-05-29T02:08:22.456153Z","steps":["trace[1683359016] 'process raft request' (duration: 24.645224ms)","trace[1683359016] 'compare' (duration: 212.754896ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:08:26.919255Z","caller":"traceutil/trace.go:171","msg":"trace[1357120293] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"148.11666ms","start":"2026-05-29T02:08:26.771109Z","end":"2026-05-29T02:08:26.919225Z","steps":["trace[1357120293] 'read index received' (duration: 147.224203ms)","trace[1357120293] 'applied index is now lower than readState.Index' (duration: 891.087µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:08:26.919325Z","caller":"traceutil/trace.go:171","msg":"trace[1413694151] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"168.207124ms","start":"2026-05-29T02:08:26.751079Z","end":"2026-05-29T02:08:26.919286Z","steps":["trace[1413694151] 'process raft request' (duration: 167.571471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:26.919443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.317035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-29T02:08:26.91951Z","caller":"traceutil/trace.go:171","msg":"trace[1042711485] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:1; response_revision:591; }","duration":"148.467738ms","start":"2026-05-29T02:08:26.771026Z","end":"2026-05-29T02:08:26.919494Z","steps":["trace[1042711485] 'agreement among raft nodes before linearized reading' (duration: 148.330325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:27.398985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.310505ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T02:08:27.399106Z","caller":"traceutil/trace.go:171","msg":"trace[1411438075] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"434.050295ms","start":"2026-05-29T02:08:26.965035Z","end":"2026-05-29T02:08:27.399085Z","steps":["trace[1411438075] 'process raft request' (duration: 261.549986ms)","trace[1411438075] 'compare' (duration: 172.160102ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:08:27.399173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:26.965016Z","time spent":"434.129017ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:08:27.402895Z","caller":"traceutil/trace.go:171","msg":"trace[1474027578] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:618; }","duration":"297.084678ms","start":"2026-05-29T02:08:27.105791Z","end":"2026-05-29T02:08:27.402876Z","steps":["trace[1474027578] 'read index received' (duration: 120.824083ms)","trace[1474027578] 'applied index is now lower than readState.Index' (duration: 176.259715ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:08:27.403018Z","caller":"traceutil/trace.go:171","msg":"trace[533312915] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"422.450673ms","start":"2026-05-29T02:08:26.980512Z","end":"2026-05-29T02:08:27.402962Z","steps":["trace[533312915] 'process raft request' (duration: 422.28334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:27.403132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.336003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-29T02:08:27.403165Z","caller":"traceutil/trace.go:171","msg":"trace[792544361] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"297.427015ms","start":"2026-05-29T02:08:27.10573Z","end":"2026-05-29T02:08:27.403157Z","steps":["trace[792544361] 'agreement among raft nodes before linearized reading' (duration: 297.243442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:08:27.403262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:08:26.980496Z","time spent":"422.653287ms","remote":"127.0.0.1:36912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T02:08:27.403059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.189702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:08:27.403375Z","caller":"traceutil/trace.go:171","msg":"trace[1457143298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"142.549749ms","start":"2026-05-29T02:08:27.260817Z","end":"2026-05-29T02:08:27.403367Z","steps":["trace[1457143298] 'agreement among raft nodes before linearized reading' (duration: 142.190982ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:08:28.725583Z","caller":"traceutil/trace.go:171","msg":"trace[1239609253] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"101.548307ms","start":"2026-05-29T02:08:28.623993Z","end":"2026-05-29T02:08:28.725541Z","steps":["trace[1239609253] 'process raft request' (duration: 100.293891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:09:59.189086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.238001ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T02:09:59.18923Z","caller":"traceutil/trace.go:171","msg":"trace[1309949501] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"179.861512ms","start":"2026-05-29T02:09:59.009345Z","end":"2026-05-29T02:09:59.189206Z","steps":["trace[1309949501] 'process raft request' (duration: 55.394576ms)","trace[1309949501] 'compare' (duration: 124.106338ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:09:59.39107Z","caller":"traceutil/trace.go:171","msg":"trace[259156924] linearizableReadLoop","detail":"{readStateIndex:1898; appliedIndex:1897; }","duration":"169.456742ms","start":"2026-05-29T02:09:59.221575Z","end":"2026-05-29T02:09:59.391031Z","steps":["trace[259156924] 'read index received' (duration: 162.007182ms)","trace[259156924] 'applied index is now lower than readState.Index' (duration: 7.44807ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:09:59.391204Z","caller":"traceutil/trace.go:171","msg":"trace[323449615] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"175.555835ms","start":"2026-05-29T02:09:59.215567Z","end":"2026-05-29T02:09:59.391122Z","steps":["trace[323449615] 'process raft request' (duration: 167.941672ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:09:59.391485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.883841ms","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-05-29T02:09:59.391543Z","caller":"traceutil/trace.go:171","msg":"trace[421652914] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1826; }","duration":"170.024123ms","start":"2026-05-29T02:09:59.221504Z","end":"2026-05-29T02:09:59.391528Z","steps":["trace[421652914] 'agreement among raft nodes before linearized reading' (duration: 169.734108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:09:59.391541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.318764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:09:59.391647Z","caller":"traceutil/trace.go:171","msg":"trace[1002374361] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1826; }","duration":"131.463257ms","start":"2026-05-29T02:09:59.260146Z","end":"2026-05-29T02:09:59.391609Z","steps":["trace[1002374361] 'agreement among raft nodes before linearized reading' (duration: 131.300484ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:10:11.601751Z","caller":"traceutil/trace.go:171","msg":"trace[584706879] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"109.157278ms","start":"2026-05-29T02:10:11.492556Z","end":"2026-05-29T02:10:11.601713Z","steps":["trace[584706879] 'process raft request' (duration: 94.449971ms)","trace[584706879] 'compare' (duration: 14.496752ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:10:48.177147Z","caller":"traceutil/trace.go:171","msg":"trace[90901571] linearizableReadLoop","detail":"{readStateIndex:2221; appliedIndex:2220; }","duration":"136.135327ms","start":"2026-05-29T02:10:48.040983Z","end":"2026-05-29T02:10:48.177118Z","steps":["trace[90901571] 'read index received' (duration: 135.907483ms)","trace[90901571] 'applied index is now lower than readState.Index' (duration: 226.834µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:10:48.177267Z","caller":"traceutil/trace.go:171","msg":"trace[309138905] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"167.136474ms","start":"2026-05-29T02:10:48.010093Z","end":"2026-05-29T02:10:48.177229Z","steps":["trace[309138905] 'process raft request' (duration: 166.859989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:10:48.177388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.383702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-05-29T02:10:48.177688Z","caller":"traceutil/trace.go:171","msg":"trace[1475066891] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2139; }","duration":"136.749089ms","start":"2026-05-29T02:10:48.040908Z","end":"2026-05-29T02:10:48.177657Z","steps":["trace[1475066891] 'agreement among raft nodes before linearized reading' (duration: 136.350101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:10:48.422151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.441024ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T02:10:48.422283Z","caller":"traceutil/trace.go:171","msg":"trace[1361833907] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"241.48349ms","start":"2026-05-29T02:10:48.180773Z","end":"2026-05-29T02:10:48.422257Z","steps":["trace[1361833907] 'process raft request' (duration: 64.819642ms)","trace[1361833907] 'compare' (duration: 176.26569ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:10:48.428603Z","caller":"traceutil/trace.go:171","msg":"trace[407495335] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"246.791208ms","start":"2026-05-29T02:10:48.181786Z","end":"2026-05-29T02:10:48.428577Z","steps":["trace[407495335] 'process raft request' (duration: 246.690526ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:10:48.42864Z","caller":"traceutil/trace.go:171","msg":"trace[670779148] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"247.158526ms","start":"2026-05-29T02:10:48.181449Z","end":"2026-05-29T02:10:48.428608Z","steps":["trace[670779148] 'process raft request' (duration: 246.86249ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:10:48.428802Z","caller":"traceutil/trace.go:171","msg":"trace[1744402448] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"246.256626ms","start":"2026-05-29T02:10:48.182529Z","end":"2026-05-29T02:10:48.428786Z","steps":["trace[1744402448] 'process raft request' (duration: 246.148324ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:10:48.428881Z","caller":"traceutil/trace.go:171","msg":"trace[347510973] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"246.760228ms","start":"2026-05-29T02:10:48.182071Z","end":"2026-05-29T02:10:48.428831Z","steps":["trace[347510973] 'process raft request' (duration: 246.462671ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:14:39.440592Z","caller":"traceutil/trace.go:171","msg":"trace[410011652] linearizableReadLoop","detail":"{readStateIndex:3910; appliedIndex:3909; }","duration":"180.636607ms","start":"2026-05-29T02:14:39.259922Z","end":"2026-05-29T02:14:39.440559Z","steps":["trace[410011652] 'read index received' (duration: 180.483054ms)","trace[410011652] 'applied index is now lower than readState.Index' (duration: 151.813µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:14:39.440737Z","caller":"traceutil/trace.go:171","msg":"trace[1433933351] transaction","detail":"{read_only:false; response_revision:3766; number_of_response:1; }","duration":"189.62784ms","start":"2026-05-29T02:14:39.251085Z","end":"2026-05-29T02:14:39.440713Z","steps":["trace[1433933351] 'process raft request' (duration: 189.297153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:14:39.440775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.836233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:14:39.440815Z","caller":"traceutil/trace.go:171","msg":"trace[2017221514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3766; }","duration":"180.945225ms","start":"2026-05-29T02:14:39.259859Z","end":"2026-05-29T02:14:39.440805Z","steps":["trace[2017221514] 'agreement among raft nodes before linearized reading' (duration: 180.854513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:14:39.441759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.536189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:14:39.44187Z","caller":"traceutil/trace.go:171","msg":"trace[586974681] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:3767; }","duration":"145.722463ms","start":"2026-05-29T02:14:39.296125Z","end":"2026-05-29T02:14:39.441847Z","steps":["trace[586974681] 'agreement among raft nodes before linearized reading' (duration: 145.517089ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:15:56.451541Z","caller":"traceutil/trace.go:171","msg":"trace[564936479] linearizableReadLoop","detail":"{readStateIndex:4897; appliedIndex:4896; }","duration":"101.648457ms","start":"2026-05-29T02:15:56.34987Z","end":"2026-05-29T02:15:56.451518Z","steps":["trace[564936479] 'read index received' (duration: 91.699657ms)","trace[564936479] 'applied index is now lower than readState.Index' (duration: 9.948ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:15:56.451851Z","caller":"traceutil/trace.go:171","msg":"trace[1264834073] transaction","detail":"{read_only:false; response_revision:4718; number_of_response:1; }","duration":"111.257792ms","start":"2026-05-29T02:15:56.340583Z","end":"2026-05-29T02:15:56.451841Z","steps":["trace[1264834073] 'process raft request' (duration: 100.974074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:15:56.452163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.26909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2c2cf174-2b9b-4408-b1a0-3f437ccae42d\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-05-29T02:15:56.452236Z","caller":"traceutil/trace.go:171","msg":"trace[1837878692] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2c2cf174-2b9b-4408-b1a0-3f437ccae42d; range_end:; response_count:1; response_revision:4718; }","duration":"102.384502ms","start":"2026-05-29T02:15:56.349833Z","end":"2026-05-29T02:15:56.452218Z","steps":["trace[1837878692] 'agreement among raft nodes before linearized reading' (duration: 102.160738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:15:56.469337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.983615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:22 size:526827"} {"level":"info","ts":"2026-05-29T02:15:56.469433Z","caller":"traceutil/trace.go:171","msg":"trace[1794007145] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:22; response_revision:4719; }","duration":"106.147758ms","start":"2026-05-29T02:15:56.363263Z","end":"2026-05-29T02:15:56.469411Z","steps":["trace[1794007145] 'agreement among raft nodes before linearized reading' (duration: 105.457785ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:16:04.115061Z","caller":"traceutil/trace.go:171","msg":"trace[1296394771] linearizableReadLoop","detail":"{readStateIndex:5146; appliedIndex:5145; }","duration":"218.560912ms","start":"2026-05-29T02:16:03.896473Z","end":"2026-05-29T02:16:04.115034Z","steps":["trace[1296394771] 'read index received' (duration: 218.387099ms)","trace[1296394771] 'applied index is now lower than readState.Index' (duration: 172.413µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:16:04.115193Z","caller":"traceutil/trace.go:171","msg":"trace[1178295050] transaction","detail":"{read_only:false; response_revision:4958; number_of_response:1; }","duration":"238.044265ms","start":"2026-05-29T02:16:03.877121Z","end":"2026-05-29T02:16:04.115166Z","steps":["trace[1178295050] 'process raft request' (duration: 237.699128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:16:04.115354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.864929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2b91e88f-7f08-4f2b-9e58-d307fb0e30ad\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-05-29T02:16:04.115419Z","caller":"traceutil/trace.go:171","msg":"trace[1271481997] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2b91e88f-7f08-4f2b-9e58-d307fb0e30ad; range_end:; response_count:1; response_revision:4958; }","duration":"218.97768ms","start":"2026-05-29T02:16:03.896427Z","end":"2026-05-29T02:16:04.115405Z","steps":["trace[1271481997] 'agreement among raft nodes before linearized reading' (duration: 218.748236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:16:04.119111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.73962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-29T02:16:04.119179Z","caller":"traceutil/trace.go:171","msg":"trace[249092953] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4959; }","duration":"109.846482ms","start":"2026-05-29T02:16:04.009316Z","end":"2026-05-29T02:16:04.119162Z","steps":["trace[249092953] 'agreement among raft nodes before linearized reading' (duration: 109.625787ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:16:04.119427Z","caller":"traceutil/trace.go:171","msg":"trace[64755454] transaction","detail":"{read_only:false; response_revision:4959; number_of_response:1; }","duration":"217.141774ms","start":"2026-05-29T02:16:03.902268Z","end":"2026-05-29T02:16:04.11941Z","steps":["trace[64755454] 'process raft request' (duration: 216.580703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:16:04.119764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.363892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-29T02:16:04.119819Z","caller":"traceutil/trace.go:171","msg":"trace[276294242] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4959; }","duration":"106.444244ms","start":"2026-05-29T02:16:04.013362Z","end":"2026-05-29T02:16:04.119806Z","steps":["trace[276294242] 'agreement among raft nodes before linearized reading' (duration: 106.310721ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:16:15.763575Z","caller":"traceutil/trace.go:171","msg":"trace[1440330150] transaction","detail":"{read_only:false; response_revision:5185; number_of_response:1; }","duration":"150.241447ms","start":"2026-05-29T02:16:15.613291Z","end":"2026-05-29T02:16:15.763533Z","steps":["trace[1440330150] 'process raft request' (duration: 56.688782ms)","trace[1440330150] 'compare' (duration: 93.241639ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:16:15.763781Z","caller":"traceutil/trace.go:171","msg":"trace[753190311] transaction","detail":"{read_only:false; response_revision:5186; number_of_response:1; }","duration":"150.427691ms","start":"2026-05-29T02:16:15.613324Z","end":"2026-05-29T02:16:15.763751Z","steps":["trace[753190311] 'process raft request' (duration: 150.087664ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:16:15.763882Z","caller":"traceutil/trace.go:171","msg":"trace[814658093] transaction","detail":"{read_only:false; response_revision:5187; number_of_response:1; }","duration":"149.800158ms","start":"2026-05-29T02:16:15.614055Z","end":"2026-05-29T02:16:15.763855Z","steps":["trace[814658093] 'process raft request' (duration: 149.432911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:16:16.173561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.964071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/goldpinger-s5497\" ","response":"range_response_count:1 size:4995"} {"level":"info","ts":"2026-05-29T02:16:16.173652Z","caller":"traceutil/trace.go:171","msg":"trace[1855810993] range","detail":"{range_begin:/registry/pods/monitoring/goldpinger-s5497; range_end:; response_count:1; response_revision:5190; }","duration":"153.073943ms","start":"2026-05-29T02:16:16.020556Z","end":"2026-05-29T02:16:16.17363Z","steps":["trace[1855810993] 'range keys from in-memory index tree' (duration: 152.797237ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:16:16.250771Z","caller":"traceutil/trace.go:171","msg":"trace[1244089670] transaction","detail":"{read_only:false; response_revision:5191; number_of_response:1; }","duration":"100.379422ms","start":"2026-05-29T02:16:16.15037Z","end":"2026-05-29T02:16:16.250749Z","steps":["trace[1244089670] 'process raft request' (duration: 100.243959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:16:23.603771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.523604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-29T02:16:23.603904Z","caller":"traceutil/trace.go:171","msg":"trace[1358109903] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5379; }","duration":"241.74409ms","start":"2026-05-29T02:16:23.362133Z","end":"2026-05-29T02:16:23.603877Z","steps":["trace[1358109903] 'range keys from in-memory index tree' (duration: 241.242649ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:16:23.628285Z","caller":"traceutil/trace.go:171","msg":"trace[149034620] linearizableReadLoop","detail":"{readStateIndex:5581; appliedIndex:5580; }","duration":"138.304986ms","start":"2026-05-29T02:16:23.489957Z","end":"2026-05-29T02:16:23.628262Z","steps":["trace[149034620] 'read index received' (duration: 138.138152ms)","trace[149034620] 'applied index is now lower than readState.Index' (duration: 165.214µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:16:23.628376Z","caller":"traceutil/trace.go:171","msg":"trace[1868703131] transaction","detail":"{read_only:false; response_revision:5380; number_of_response:1; }","duration":"194.947546ms","start":"2026-05-29T02:16:23.433397Z","end":"2026-05-29T02:16:23.628344Z","steps":["trace[1868703131] 'process raft request' (duration: 194.702742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:16:23.62849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.52529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ec546040-5d52-412e-9b6d-d1364d302b0c\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-29T02:16:23.628554Z","caller":"traceutil/trace.go:171","msg":"trace[1412655401] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ec546040-5d52-412e-9b6d-d1364d302b0c; range_end:; response_count:1; response_revision:5380; }","duration":"138.743394ms","start":"2026-05-29T02:16:23.489793Z","end":"2026-05-29T02:16:23.628537Z","steps":["trace[1412655401] 'agreement among raft nodes before linearized reading' (duration: 138.55719ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:16:33.39492Z","caller":"traceutil/trace.go:171","msg":"trace[2133638989] linearizableReadLoop","detail":"{readStateIndex:5711; appliedIndex:5708; }","duration":"112.67646ms","start":"2026-05-29T02:16:33.282215Z","end":"2026-05-29T02:16:33.394891Z","steps":["trace[2133638989] 'read index received' (duration: 47.789793ms)","trace[2133638989] 'applied index is now lower than readState.Index' (duration: 64.885577ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:16:33.394954Z","caller":"traceutil/trace.go:171","msg":"trace[1881502966] transaction","detail":"{read_only:false; response_revision:5507; number_of_response:1; }","duration":"112.814053ms","start":"2026-05-29T02:16:33.282104Z","end":"2026-05-29T02:16:33.394918Z","steps":["trace[1881502966] 'process raft request' (duration: 112.65646ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:16:33.394996Z","caller":"traceutil/trace.go:171","msg":"trace[1732212729] transaction","detail":"{read_only:false; response_revision:5508; number_of_response:1; }","duration":"111.917745ms","start":"2026-05-29T02:16:33.283043Z","end":"2026-05-29T02:16:33.39496Z","steps":["trace[1732212729] 'process raft request' (duration: 111.794622ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:16:33.394954Z","caller":"traceutil/trace.go:171","msg":"trace[347860622] transaction","detail":"{read_only:false; response_revision:5506; number_of_response:1; }","duration":"113.939945ms","start":"2026-05-29T02:16:33.28096Z","end":"2026-05-29T02:16:33.3949Z","steps":["trace[347860622] 'process raft request' (duration: 112.865433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:16:33.395154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.910935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-05-29T02:16:33.395211Z","caller":"traceutil/trace.go:171","msg":"trace[346333982] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5508; }","duration":"113.007536ms","start":"2026-05-29T02:16:33.282191Z","end":"2026-05-29T02:16:33.395198Z","steps":["trace[346333982] 'agreement among raft nodes before linearized reading' (duration: 112.814223ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:17:43.711964Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2964} {"level":"info","ts":"2026-05-29T02:17:43.785154Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2964,"took":"71.01698ms","hash":3749320662,"current-db-size-bytes":58966016,"current-db-size":"59 MB","current-db-size-in-use-bytes":34390016,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-29T02:17:43.785242Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3749320662,"revision":2964,"compact-revision":-1} {"level":"info","ts":"2026-05-29T02:19:32.158898Z","caller":"traceutil/trace.go:171","msg":"trace[360924920] transaction","detail":"{read_only:false; response_revision:6732; number_of_response:1; }","duration":"396.361602ms","start":"2026-05-29T02:19:31.762511Z","end":"2026-05-29T02:19:32.158872Z","steps":["trace[360924920] 'process raft request' (duration: 396.227729ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:19:32.15892Z","caller":"traceutil/trace.go:171","msg":"trace[2008330261] linearizableReadLoop","detail":"{readStateIndex:6975; appliedIndex:6975; }","duration":"396.084477ms","start":"2026-05-29T02:19:31.762801Z","end":"2026-05-29T02:19:32.158886Z","steps":["trace[2008330261] 'read index received' (duration: 396.067746ms)","trace[2008330261] 'applied index is now lower than readState.Index' (duration: 13.861µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:19:32.159047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:19:31.762501Z","time spent":"396.476875ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:19:32.159197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.371202ms","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-05-29T02:19:32.159242Z","caller":"traceutil/trace.go:171","msg":"trace[1296106728] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6732; }","duration":"396.470354ms","start":"2026-05-29T02:19:31.76276Z","end":"2026-05-29T02:19:32.15923Z","steps":["trace[1296106728] 'agreement among raft nodes before linearized reading' (duration: 396.27074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:19:32.159283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:19:31.762742Z","time spent":"396.532376ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-29T02:19:32.164852Z","caller":"traceutil/trace.go:171","msg":"trace[1844544131] transaction","detail":"{read_only:false; response_revision:6733; number_of_response:1; }","duration":"399.947283ms","start":"2026-05-29T02:19:31.764886Z","end":"2026-05-29T02:19:32.164833Z","steps":["trace[1844544131] 'process raft request' (duration: 399.648447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:19:32.164946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:19:31.764869Z","time spent":"400.028975ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:19:32.16514Z","caller":"traceutil/trace.go:171","msg":"trace[2130386081] transaction","detail":"{read_only:false; response_revision:6735; number_of_response:1; }","duration":"397.79859ms","start":"2026-05-29T02:19:31.767331Z","end":"2026-05-29T02:19:32.165129Z","steps":["trace[2130386081] 'process raft request' (duration: 397.558965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:19:32.165202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:19:31.767322Z","time spent":"397.850181ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:19:32.165188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.205699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-29T02:19:32.165188Z","caller":"traceutil/trace.go:171","msg":"trace[328811727] transaction","detail":"{read_only:false; response_revision:6734; number_of_response:1; }","duration":"399.163307ms","start":"2026-05-29T02:19:31.765989Z","end":"2026-05-29T02:19:32.165152Z","steps":["trace[328811727] 'process raft request' (duration: 398.817021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:19:32.165396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.599285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T02:19:32.165431Z","caller":"traceutil/trace.go:171","msg":"trace[951919107] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6735; }","duration":"390.656286ms","start":"2026-05-29T02:19:31.774765Z","end":"2026-05-29T02:19:32.165421Z","steps":["trace[951919107] 'agreement among raft nodes before linearized reading' (duration: 390.517273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:19:32.165467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:19:31.774754Z","time spent":"390.707008ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-29T02:19:32.165331Z","caller":"traceutil/trace.go:171","msg":"trace[206398006] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6735; }","duration":"402.419263ms","start":"2026-05-29T02:19:31.762875Z","end":"2026-05-29T02:19:32.165295Z","steps":["trace[206398006] 'agreement among raft nodes before linearized reading' (duration: 402.089957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:19:32.165544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:19:31.765964Z","time spent":"399.431432ms","remote":"127.0.0.1:36758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6923,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:19:32.165606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:19:31.762855Z","time spent":"402.72837ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T02:22:31.512582Z","caller":"traceutil/trace.go:171","msg":"trace[1364103549] linearizableReadLoop","detail":"{readStateIndex:8487; appliedIndex:8486; }","duration":"115.734689ms","start":"2026-05-29T02:22:31.39682Z","end":"2026-05-29T02:22:31.512555Z","steps":["trace[1364103549] 'read index received' (duration: 115.691358ms)","trace[1364103549] 'applied index is now lower than readState.Index' (duration: 41.841µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:22:31.51279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.959793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-gnd48\" ","response":"range_response_count:1 size:6523"} {"level":"info","ts":"2026-05-29T02:22:31.512822Z","caller":"traceutil/trace.go:171","msg":"trace[1378945566] range","detail":"{range_begin:/registry/pods/openstack/keepalived-gnd48; range_end:; response_count:1; response_revision:8197; }","duration":"116.057526ms","start":"2026-05-29T02:22:31.396756Z","end":"2026-05-29T02:22:31.512813Z","steps":["trace[1378945566] 'agreement among raft nodes before linearized reading' (duration: 115.903652ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:22:31.512832Z","caller":"traceutil/trace.go:171","msg":"trace[900196623] transaction","detail":"{read_only:false; response_revision:8197; number_of_response:1; }","duration":"171.184516ms","start":"2026-05-29T02:22:31.341608Z","end":"2026-05-29T02:22:31.512792Z","steps":["trace[900196623] 'process raft request' (duration: 170.794778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:22:31.672296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.810611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T02:22:31.672402Z","caller":"traceutil/trace.go:171","msg":"trace[1976963664] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:8197; }","duration":"129.968195ms","start":"2026-05-29T02:22:31.542413Z","end":"2026-05-29T02:22:31.672381Z","steps":["trace[1976963664] 'count revisions from in-memory index tree' (duration: 129.686079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:22:31.672894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.09876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:22:31.672985Z","caller":"traceutil/trace.go:171","msg":"trace[1796679628] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:8197; }","duration":"143.834676ms","start":"2026-05-29T02:22:31.529132Z","end":"2026-05-29T02:22:31.672967Z","steps":["trace[1796679628] 'count revisions from in-memory index tree' (duration: 142.977298ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:22:43.719111Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6093} {"level":"info","ts":"2026-05-29T02:22:43.821633Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6093,"took":"100.539974ms","hash":1567846445,"current-db-size-bytes":58966016,"current-db-size":"59 MB","current-db-size-in-use-bytes":23199744,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-29T02:22:43.821718Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1567846445,"revision":6093,"compact-revision":2964} {"level":"info","ts":"2026-05-29T02:22:59.761819Z","caller":"traceutil/trace.go:171","msg":"trace[1562668690] linearizableReadLoop","detail":"{readStateIndex:8704; appliedIndex:8703; }","duration":"138.363575ms","start":"2026-05-29T02:22:59.623423Z","end":"2026-05-29T02:22:59.761786Z","steps":["trace[1562668690] 'read index received' (duration: 138.086399ms)","trace[1562668690] 'applied index is now lower than readState.Index' (duration: 275.976µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:22:59.762032Z","caller":"traceutil/trace.go:171","msg":"trace[15911853] transaction","detail":"{read_only:false; response_revision:8404; number_of_response:1; }","duration":"156.865467ms","start":"2026-05-29T02:22:59.605154Z","end":"2026-05-29T02:22:59.76202Z","steps":["trace[15911853] 'process raft request' (duration: 156.411237ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:22:59.762301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.858985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T02:22:59.762383Z","caller":"traceutil/trace.go:171","msg":"trace[1513095913] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8404; }","duration":"139.237223ms","start":"2026-05-29T02:22:59.623125Z","end":"2026-05-29T02:22:59.762362Z","steps":["trace[1513095913] 'agreement among raft nodes before linearized reading' (duration: 139.062009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:22:59.977411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.391141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-29T02:22:59.977497Z","caller":"traceutil/trace.go:171","msg":"trace[1694066891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8404; }","duration":"111.532494ms","start":"2026-05-29T02:22:59.865945Z","end":"2026-05-29T02:22:59.977477Z","steps":["trace[1694066891] 'range keys from in-memory index tree' (duration: 111.148096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:22:59.977686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.297091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-29T02:22:59.977793Z","caller":"traceutil/trace.go:171","msg":"trace[79373654] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8404; }","duration":"115.468394ms","start":"2026-05-29T02:22:59.862306Z","end":"2026-05-29T02:22:59.977774Z","steps":["trace[79373654] 'range keys from in-memory index tree' (duration: 114.965913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:23:12.057787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.900015ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T02:23:12.057944Z","caller":"traceutil/trace.go:171","msg":"trace[1271962229] transaction","detail":"{read_only:false; response_revision:8508; number_of_response:1; }","duration":"253.39888ms","start":"2026-05-29T02:23:11.804524Z","end":"2026-05-29T02:23:12.057923Z","steps":["trace[1271962229] 'process raft request' (duration: 123.273341ms)","trace[1271962229] 'compare' (duration: 129.751982ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:23:12.088261Z","caller":"traceutil/trace.go:171","msg":"trace[1205393186] linearizableReadLoop","detail":"{readStateIndex:8815; appliedIndex:8813; }","duration":"245.382559ms","start":"2026-05-29T02:23:11.842851Z","end":"2026-05-29T02:23:12.088234Z","steps":["trace[1205393186] 'read index received' (duration: 84.939929ms)","trace[1205393186] 'applied index is now lower than readState.Index' (duration: 160.44092ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:23:12.088308Z","caller":"traceutil/trace.go:171","msg":"trace[80737642] transaction","detail":"{read_only:false; response_revision:8509; number_of_response:1; }","duration":"275.771531ms","start":"2026-05-29T02:23:11.812511Z","end":"2026-05-29T02:23:12.088283Z","steps":["trace[80737642] 'process raft request' (duration: 275.600147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:23:12.088494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.634474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-gnd48\" ","response":"range_response_count:1 size:6523"} {"level":"info","ts":"2026-05-29T02:23:12.088536Z","caller":"traceutil/trace.go:171","msg":"trace[870346052] range","detail":"{range_begin:/registry/pods/openstack/keepalived-gnd48; range_end:; response_count:1; response_revision:8509; }","duration":"245.730535ms","start":"2026-05-29T02:23:11.842795Z","end":"2026-05-29T02:23:12.088525Z","steps":["trace[870346052] 'agreement among raft nodes before linearized reading' (duration: 245.562092ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:23:12.801207Z","caller":"traceutil/trace.go:171","msg":"trace[392132993] transaction","detail":"{read_only:false; response_revision:8514; number_of_response:1; }","duration":"111.937493ms","start":"2026-05-29T02:23:12.689245Z","end":"2026-05-29T02:23:12.801182Z","steps":["trace[392132993] 'process raft request' (duration: 111.77159ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:23:12.90531Z","caller":"traceutil/trace.go:171","msg":"trace[1772192215] transaction","detail":"{read_only:false; response_revision:8515; number_of_response:1; }","duration":"204.858993ms","start":"2026-05-29T02:23:12.700423Z","end":"2026-05-29T02:23:12.905282Z","steps":["trace[1772192215] 'process raft request' (duration: 190.420293ms)","trace[1772192215] 'compare' (duration: 13.9373ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:23:54.03033Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002404.wal"} {"level":"info","ts":"2026-05-29T02:24:27.700051Z","caller":"traceutil/trace.go:171","msg":"trace[1207753165] transaction","detail":"{read_only:false; response_revision:9253; number_of_response:1; }","duration":"123.412247ms","start":"2026-05-29T02:24:27.576611Z","end":"2026-05-29T02:24:27.700023Z","steps":["trace[1207753165] 'process raft request' (duration: 123.243963ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:25:22.79069Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f170c75a1d97cc","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-29T02:25:22.797444Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-29T02:25:22.797517Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-29T02:27:43.72634Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8269} {"level":"info","ts":"2026-05-29T02:27:43.806885Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8269,"took":"77.95502ms","hash":3701632268,"current-db-size-bytes":58966016,"current-db-size":"59 MB","current-db-size-in-use-bytes":26435584,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-29T02:27:43.806962Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3701632268,"revision":8269,"compact-revision":6093} {"level":"info","ts":"2026-05-29T02:32:43.734202Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10928} {"level":"info","ts":"2026-05-29T02:32:43.81403Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10928,"took":"77.688624ms","hash":3033195693,"current-db-size-bytes":58966016,"current-db-size":"59 MB","current-db-size-in-use-bytes":28250112,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-29T02:32:43.814079Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3033195693,"revision":10928,"compact-revision":8269} {"level":"warn","ts":"2026-05-29T02:34:14.153421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.416264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:34:14.153502Z","caller":"traceutil/trace.go:171","msg":"trace[2129803980] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:13564; }","duration":"124.558157ms","start":"2026-05-29T02:34:14.028927Z","end":"2026-05-29T02:34:14.153485Z","steps":["trace[2129803980] 'count revisions from in-memory index tree' (duration: 124.334313ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:34:30.204683Z","caller":"traceutil/trace.go:171","msg":"trace[1425035049] transaction","detail":"{read_only:false; response_revision:13666; number_of_response:1; }","duration":"265.287661ms","start":"2026-05-29T02:34:29.939371Z","end":"2026-05-29T02:34:30.204658Z","steps":["trace[1425035049] 'process raft request' (duration: 265.110688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:34:30.478527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.07478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:34:30.478678Z","caller":"traceutil/trace.go:171","msg":"trace[18572594] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13666; }","duration":"218.260024ms","start":"2026-05-29T02:34:30.260398Z","end":"2026-05-29T02:34:30.478658Z","steps":["trace[18572594] 'range keys from in-memory index tree' (duration: 218.016779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:34:30.480179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.736243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:86 size:929855"} {"level":"info","ts":"2026-05-29T02:34:30.480334Z","caller":"traceutil/trace.go:171","msg":"trace[758679491] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:86; response_revision:13666; }","duration":"147.986288ms","start":"2026-05-29T02:34:30.332314Z","end":"2026-05-29T02:34:30.4803Z","steps":["trace[758679491] 'range keys from in-memory index tree' (duration: 146.261804ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:34:30.660548Z","caller":"traceutil/trace.go:171","msg":"trace[143032056] transaction","detail":"{read_only:false; response_revision:13667; number_of_response:1; }","duration":"106.191118ms","start":"2026-05-29T02:34:30.554326Z","end":"2026-05-29T02:34:30.660517Z","steps":["trace[143032056] 'process raft request' (duration: 106.001444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:36:07.105542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.102582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-05-29T02:36:07.105629Z","caller":"traceutil/trace.go:171","msg":"trace[1738651584] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14850; }","duration":"156.238085ms","start":"2026-05-29T02:36:06.949371Z","end":"2026-05-29T02:36:07.105609Z","steps":["trace[1738651584] 'range keys from in-memory index tree' (duration: 155.881818ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:36:07.177981Z","caller":"traceutil/trace.go:171","msg":"trace[2079399174] linearizableReadLoop","detail":"{readStateIndex:15356; appliedIndex:15355; }","duration":"120.333572ms","start":"2026-05-29T02:36:07.057613Z","end":"2026-05-29T02:36:07.177946Z","steps":["trace[2079399174] 'read index received' (duration: 120.101767ms)","trace[2079399174] 'applied index is now lower than readState.Index' (duration: 230.585µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:36:07.178106Z","caller":"traceutil/trace.go:171","msg":"trace[741180032] transaction","detail":"{read_only:false; response_revision:14851; number_of_response:1; }","duration":"182.895823ms","start":"2026-05-29T02:36:06.995184Z","end":"2026-05-29T02:36:07.17808Z","steps":["trace[741180032] 'process raft request' (duration: 182.517305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:36:07.178151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.520917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T02:36:07.178201Z","caller":"traceutil/trace.go:171","msg":"trace[2114336268] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:14851; }","duration":"120.642179ms","start":"2026-05-29T02:36:07.057542Z","end":"2026-05-29T02:36:07.178184Z","steps":["trace[2114336268] 'agreement among raft nodes before linearized reading' (duration: 120.536317ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:36:07.98694Z","caller":"traceutil/trace.go:171","msg":"trace[712260470] transaction","detail":"{read_only:false; response_revision:14856; number_of_response:1; }","duration":"100.277489ms","start":"2026-05-29T02:36:07.886628Z","end":"2026-05-29T02:36:07.986906Z","steps":["trace[712260470] 'process raft request' (duration: 100.037264ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:36:08.43835Z","caller":"traceutil/trace.go:171","msg":"trace[1062399419] linearizableReadLoop","detail":"{readStateIndex:15364; appliedIndex:15361; }","duration":"200.21316ms","start":"2026-05-29T02:36:08.238104Z","end":"2026-05-29T02:36:08.438317Z","steps":["trace[1062399419] 'read index received' (duration: 34.069746ms)","trace[1062399419] 'applied index is now lower than readState.Index' (duration: 166.142004ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:36:08.438379Z","caller":"traceutil/trace.go:171","msg":"trace[1556806346] transaction","detail":"{read_only:false; response_revision:14858; number_of_response:1; }","duration":"205.690321ms","start":"2026-05-29T02:36:08.232646Z","end":"2026-05-29T02:36:08.438336Z","steps":["trace[1556806346] 'process raft request' (duration: 118.405344ms)","trace[1556806346] 'compare' (duration: 86.984381ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:36:08.438746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.854511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T02:36:08.438821Z","caller":"traceutil/trace.go:171","msg":"trace[837388485] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14859; }","duration":"178.976804ms","start":"2026-05-29T02:36:08.259821Z","end":"2026-05-29T02:36:08.438798Z","steps":["trace[837388485] 'agreement among raft nodes before linearized reading' (duration: 178.7917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:36:08.438882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.744412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-gnd48\" ","response":"range_response_count:1 size:6523"} {"level":"info","ts":"2026-05-29T02:36:08.438961Z","caller":"traceutil/trace.go:171","msg":"trace[1914800633] range","detail":"{range_begin:/registry/pods/openstack/keepalived-gnd48; range_end:; response_count:1; response_revision:14859; }","duration":"200.859913ms","start":"2026-05-29T02:36:08.23808Z","end":"2026-05-29T02:36:08.43894Z","steps":["trace[1914800633] 'agreement among raft nodes before linearized reading' (duration: 200.565288ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:36:08.439484Z","caller":"traceutil/trace.go:171","msg":"trace[1696468552] transaction","detail":"{read_only:false; response_revision:14859; number_of_response:1; }","duration":"205.922556ms","start":"2026-05-29T02:36:08.233539Z","end":"2026-05-29T02:36:08.439462Z","steps":["trace[1696468552] 'process raft request' (duration: 204.697711ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:37:43.745372Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12978} {"level":"info","ts":"2026-05-29T02:37:43.823737Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12978,"took":"75.504121ms","hash":847785528,"current-db-size-bytes":58966016,"current-db-size":"59 MB","current-db-size-in-use-bytes":31461376,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-29T02:37:43.823795Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":847785528,"revision":12978,"compact-revision":10928} {"level":"warn","ts":"2026-05-29T02:41:50.419434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.583227ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-29T02:41:50.419527Z","caller":"traceutil/trace.go:171","msg":"trace[1708313014] linearizableReadLoop","detail":"{readStateIndex:18206; appliedIndex:18205; }","duration":"158.855865ms","start":"2026-05-29T02:41:50.260659Z","end":"2026-05-29T02:41:50.419515Z","steps":["trace[1708313014] 'read index received' (duration: 8.967961ms)","trace[1708313014] 'applied index is now lower than readState.Index' (duration: 149.887124ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:41:50.419596Z","caller":"traceutil/trace.go:171","msg":"trace[2025829133] transaction","detail":"{read_only:false; response_revision:17613; number_of_response:1; }","duration":"192.82256ms","start":"2026-05-29T02:41:50.226765Z","end":"2026-05-29T02:41:50.419588Z","steps":["trace[2025829133] 'process raft request' (duration: 42.973837ms)","trace[2025829133] 'compare' (duration: 149.478435ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:41:50.419752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.0916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-29T02:41:50.419776Z","caller":"traceutil/trace.go:171","msg":"trace[1251421605] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17613; }","duration":"159.11493ms","start":"2026-05-29T02:41:50.260653Z","end":"2026-05-29T02:41:50.419768Z","steps":["trace[1251421605] 'agreement among raft nodes before linearized reading' (duration: 159.021318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:41:50.419949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.340098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-pkj79\" ","response":"range_response_count:1 size:22126"} {"level":"info","ts":"2026-05-29T02:41:50.419972Z","caller":"traceutil/trace.go:171","msg":"trace[1664143195] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-pkj79; range_end:; response_count:1; response_revision:17613; }","duration":"124.389329ms","start":"2026-05-29T02:41:50.295577Z","end":"2026-05-29T02:41:50.419967Z","steps":["trace[1664143195] 'agreement among raft nodes before linearized reading' (duration: 124.304257ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:41:52.17541Z","caller":"traceutil/trace.go:171","msg":"trace[1497377713] transaction","detail":"{read_only:false; response_revision:17631; number_of_response:1; }","duration":"107.677333ms","start":"2026-05-29T02:41:52.067706Z","end":"2026-05-29T02:41:52.175384Z","steps":["trace[1497377713] 'process raft request' (duration: 107.5093ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:42:43.752413Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15477} {"level":"info","ts":"2026-05-29T02:42:43.846842Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15477,"took":"91.443015ms","hash":1476962248,"current-db-size-bytes":58966016,"current-db-size":"59 MB","current-db-size-in-use-bytes":34086912,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-29T02:42:43.846903Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1476962248,"revision":15477,"compact-revision":12978} {"level":"info","ts":"2026-05-29T02:45:26.758659Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f170c75a1d97cc","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-29T02:45:26.76423Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-29T02:45:26.76435Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"warn","ts":"2026-05-29T02:46:32.791856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.524181ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-29T02:46:32.792377Z","caller":"traceutil/trace.go:171","msg":"trace[761793180] linearizableReadLoop","detail":"{readStateIndex:20383; appliedIndex:20381; }","duration":"222.113679ms","start":"2026-05-29T02:46:32.570247Z","end":"2026-05-29T02:46:32.79236Z","steps":["trace[761793180] 'read index received' (duration: 94.090908ms)","trace[761793180] 'applied index is now lower than readState.Index' (duration: 128.021701ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:46:32.792396Z","caller":"traceutil/trace.go:171","msg":"trace[2055910097] transaction","detail":"{read_only:false; response_revision:19715; number_of_response:1; }","duration":"232.523449ms","start":"2026-05-29T02:46:32.559837Z","end":"2026-05-29T02:46:32.792361Z","steps":["trace[2055910097] 'process raft request' (duration: 104.409106ms)","trace[2055910097] 'compare' (duration: 127.423459ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:46:32.792517Z","caller":"traceutil/trace.go:171","msg":"trace[1226323442] transaction","detail":"{read_only:false; response_revision:19716; number_of_response:1; }","duration":"232.749213ms","start":"2026-05-29T02:46:32.559728Z","end":"2026-05-29T02:46:32.792477Z","steps":["trace[1226323442] 'process raft request' (duration: 232.5706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:46:32.792604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.332333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-29T02:46:32.793161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.031514ms","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-05-29T02:46:32.793235Z","caller":"traceutil/trace.go:171","msg":"trace[105906592] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:19716; }","duration":"140.150606ms","start":"2026-05-29T02:46:32.65307Z","end":"2026-05-29T02:46:32.793221Z","steps":["trace[105906592] 'agreement among raft nodes before linearized reading' (duration: 139.923982ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:46:32.793341Z","caller":"traceutil/trace.go:171","msg":"trace[1934438840] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:19716; }","duration":"222.863944ms","start":"2026-05-29T02:46:32.570219Z","end":"2026-05-29T02:46:32.793083Z","steps":["trace[1934438840] 'agreement among raft nodes before linearized reading' (duration: 222.310012ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:46:49.009586Z","caller":"traceutil/trace.go:171","msg":"trace[1226909941] transaction","detail":"{read_only:false; response_revision:19796; number_of_response:1; }","duration":"149.139587ms","start":"2026-05-29T02:46:48.860421Z","end":"2026-05-29T02:46:49.00956Z","steps":["trace[1226909941] 'process raft request' (duration: 148.944503ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:46:49.213391Z","caller":"traceutil/trace.go:171","msg":"trace[258928845] transaction","detail":"{read_only:false; response_revision:19798; number_of_response:1; }","duration":"338.439814ms","start":"2026-05-29T02:46:48.874934Z","end":"2026-05-29T02:46:49.213374Z","steps":["trace[258928845] 'process raft request' (duration: 338.344472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:46:49.213528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:46:48.874921Z","time spent":"338.533016ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:46:49.213377Z","caller":"traceutil/trace.go:171","msg":"trace[1764851010] transaction","detail":"{read_only:false; response_revision:19797; number_of_response:1; }","duration":"348.954887ms","start":"2026-05-29T02:46:48.864395Z","end":"2026-05-29T02:46:49.21335Z","steps":["trace[1764851010] 'process raft request' (duration: 348.740262ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:46:49.213686Z","caller":"traceutil/trace.go:171","msg":"trace[855211545] transaction","detail":"{read_only:false; response_revision:19799; number_of_response:1; }","duration":"236.220173ms","start":"2026-05-29T02:46:48.977425Z","end":"2026-05-29T02:46:49.213645Z","steps":["trace[855211545] 'process raft request' (duration: 235.897516ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:46:49.213621Z","caller":"traceutil/trace.go:171","msg":"trace[1000151735] linearizableReadLoop","detail":"{readStateIndex:20468; appliedIndex:20465; }","duration":"333.714329ms","start":"2026-05-29T02:46:48.879858Z","end":"2026-05-29T02:46:49.213573Z","steps":["trace[1000151735] 'read index received' (duration: 129.578373ms)","trace[1000151735] 'applied index is now lower than readState.Index' (duration: 204.132586ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:46:49.21386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:46:48.864375Z","time spent":"349.354335ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:46:49.213946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.279297ms","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-05-29T02:46:49.213998Z","caller":"traceutil/trace.go:171","msg":"trace[170243134] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:19799; }","duration":"134.374019ms","start":"2026-05-29T02:46:49.079609Z","end":"2026-05-29T02:46:49.213983Z","steps":["trace[170243134] 'agreement among raft nodes before linearized reading' (duration: 134.258257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:46:49.213992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.096607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-29T02:46:49.214057Z","caller":"traceutil/trace.go:171","msg":"trace[1578275153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19799; }","duration":"334.222549ms","start":"2026-05-29T02:46:48.879818Z","end":"2026-05-29T02:46:49.214041Z","steps":["trace[1578275153] 'agreement among raft nodes before linearized reading' (duration: 333.879893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:46:49.214107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:46:48.879803Z","time spent":"334.28695ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-29T02:46:49.325164Z","caller":"traceutil/trace.go:171","msg":"trace[83057407] transaction","detail":"{read_only:false; response_revision:19800; number_of_response:1; }","duration":"105.083719ms","start":"2026-05-29T02:46:49.220049Z","end":"2026-05-29T02:46:49.325133Z","steps":["trace[83057407] 'process raft request' (duration: 100.234621ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:46:49.340419Z","caller":"traceutil/trace.go:171","msg":"trace[750569489] transaction","detail":"{read_only:false; response_revision:19801; number_of_response:1; }","duration":"120.273445ms","start":"2026-05-29T02:46:49.220118Z","end":"2026-05-29T02:46:49.340392Z","steps":["trace[750569489] 'process raft request' (duration: 119.919758ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:46:49.814903Z","caller":"traceutil/trace.go:171","msg":"trace[636725164] transaction","detail":"{read_only:false; response_revision:19802; number_of_response:1; }","duration":"124.043351ms","start":"2026-05-29T02:46:49.690828Z","end":"2026-05-29T02:46:49.814871Z","steps":["trace[636725164] 'process raft request' (duration: 123.766366ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:46:49.983811Z","caller":"traceutil/trace.go:171","msg":"trace[408223222] transaction","detail":"{read_only:false; response_revision:19803; number_of_response:1; }","duration":"164.548048ms","start":"2026-05-29T02:46:49.819234Z","end":"2026-05-29T02:46:49.983782Z","steps":["trace[408223222] 'process raft request' (duration: 96.859643ms)","trace[408223222] 'compare' (duration: 67.170775ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:47:43.758768Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18079} {"level":"info","ts":"2026-05-29T02:47:43.869979Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18079,"took":"107.714352ms","hash":2322489796,"current-db-size-bytes":58966016,"current-db-size":"59 MB","current-db-size-in-use-bytes":30244864,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-29T02:47:43.870038Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2322489796,"revision":18079,"compact-revision":15477} {"level":"warn","ts":"2026-05-29T02:50:27.222751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.220264ms","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-05-29T02:50:27.222853Z","caller":"traceutil/trace.go:171","msg":"trace[975561016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22135; }","duration":"175.392717ms","start":"2026-05-29T02:50:27.047435Z","end":"2026-05-29T02:50:27.222828Z","steps":["trace[975561016] 'range keys from in-memory index tree' (duration: 174.990369ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:50:27.399909Z","caller":"traceutil/trace.go:171","msg":"trace[2137436265] linearizableReadLoop","detail":"{readStateIndex:22872; appliedIndex:22871; }","duration":"139.158887ms","start":"2026-05-29T02:50:27.260723Z","end":"2026-05-29T02:50:27.399882Z","steps":["trace[2137436265] 'read index received' (duration: 74.255128ms)","trace[2137436265] 'applied index is now lower than readState.Index' (duration: 64.902579ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:50:27.400059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.3067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-29T02:50:27.400102Z","caller":"traceutil/trace.go:171","msg":"trace[58802133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22137; }","duration":"139.409131ms","start":"2026-05-29T02:50:27.260682Z","end":"2026-05-29T02:50:27.400092Z","steps":["trace[58802133] 'agreement among raft nodes before linearized reading' (duration: 139.310669ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:50:27.400279Z","caller":"traceutil/trace.go:171","msg":"trace[1270773909] transaction","detail":"{read_only:false; response_revision:22137; number_of_response:1; }","duration":"157.065718ms","start":"2026-05-29T02:50:27.243155Z","end":"2026-05-29T02:50:27.400221Z","steps":["trace[1270773909] 'process raft request' (duration: 91.809672ms)","trace[1270773909] 'compare' (duration: 64.775206ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:52:43.765486Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20346} {"level":"info","ts":"2026-05-29T02:52:43.872417Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20346,"took":"103.236092ms","hash":779142834,"current-db-size-bytes":60661760,"current-db-size":"61 MB","current-db-size-in-use-bytes":42401792,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-05-29T02:52:43.872496Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":779142834,"revision":20346,"compact-revision":18079} {"level":"warn","ts":"2026-05-29T02:53:56.767016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.275547ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-29T02:53:56.767235Z","caller":"traceutil/trace.go:171","msg":"trace[1089925155] transaction","detail":"{read_only:false; response_revision:24222; number_of_response:1; }","duration":"274.166819ms","start":"2026-05-29T02:53:56.49298Z","end":"2026-05-29T02:53:56.767147Z","steps":["trace[1089925155] 'process raft request' (duration: 113.665517ms)","trace[1089925155] 'compare' (duration: 159.998612ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:53:57.044414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.078878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-29T02:53:57.044711Z","caller":"traceutil/trace.go:171","msg":"trace[524191991] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:1; response_revision:24223; }","duration":"121.385594ms","start":"2026-05-29T02:53:56.923281Z","end":"2026-05-29T02:53:57.044667Z","steps":["trace[524191991] 'range keys from in-memory index tree' (duration: 120.893774ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:53:57.045121Z","caller":"traceutil/trace.go:171","msg":"trace[1197671557] transaction","detail":"{read_only:false; response_revision:24224; number_of_response:1; }","duration":"117.008956ms","start":"2026-05-29T02:53:56.928077Z","end":"2026-05-29T02:53:57.045086Z","steps":["trace[1197671557] 'process raft request' (duration: 90.90446ms)","trace[1197671557] 'compare' (duration: 25.754298ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:53:57.237578Z","caller":"traceutil/trace.go:171","msg":"trace[295585239] transaction","detail":"{read_only:false; response_revision:24225; number_of_response:1; }","duration":"308.578924ms","start":"2026-05-29T02:53:56.928936Z","end":"2026-05-29T02:53:57.237515Z","steps":["trace[295585239] 'process raft request' (duration: 219.237544ms)","trace[295585239] 'compare' (duration: 89.034043ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:53:57.237701Z","caller":"traceutil/trace.go:171","msg":"trace[2071658284] transaction","detail":"{read_only:false; response_revision:24226; number_of_response:1; }","duration":"296.361657ms","start":"2026-05-29T02:53:56.941283Z","end":"2026-05-29T02:53:57.237644Z","steps":["trace[2071658284] 'process raft request' (duration: 296.291566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:53:57.237766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:53:56.928929Z","time spent":"308.741367ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:53:57.269007Z","caller":"traceutil/trace.go:171","msg":"trace[1797503621] linearizableReadLoop","detail":"{readStateIndex:25018; appliedIndex:25014; }","duration":"205.980098ms","start":"2026-05-29T02:53:57.062999Z","end":"2026-05-29T02:53:57.26898Z","steps":["trace[1797503621] 'read index received' (duration: 85.135033ms)","trace[1797503621] 'applied index is now lower than readState.Index' (duration: 120.844155ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:53:57.269288Z","caller":"traceutil/trace.go:171","msg":"trace[1686006884] transaction","detail":"{read_only:false; response_revision:24227; number_of_response:1; }","duration":"240.381021ms","start":"2026-05-29T02:53:57.028894Z","end":"2026-05-29T02:53:57.269275Z","steps":["trace[1686006884] 'process raft request' (duration: 239.797998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:53:57.269653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.636921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-29T02:53:57.269702Z","caller":"traceutil/trace.go:171","msg":"trace[1096965177] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:24227; }","duration":"206.741563ms","start":"2026-05-29T02:53:57.062947Z","end":"2026-05-29T02:53:57.269688Z","steps":["trace[1096965177] 'agreement among raft nodes before linearized reading' (duration: 206.636621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:53:57.27996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.511688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-29T02:53:57.279993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.755092ms","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-05-29T02:53:57.280026Z","caller":"traceutil/trace.go:171","msg":"trace[631190964] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:24228; }","duration":"206.6329ms","start":"2026-05-29T02:53:57.073376Z","end":"2026-05-29T02:53:57.280009Z","steps":["trace[631190964] 'agreement among raft nodes before linearized reading' (duration: 206.423786ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:53:57.280047Z","caller":"traceutil/trace.go:171","msg":"trace[1622310743] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24228; }","duration":"129.872305ms","start":"2026-05-29T02:53:57.15016Z","end":"2026-05-29T02:53:57.280032Z","steps":["trace[1622310743] 'agreement among raft nodes before linearized reading' (duration: 129.715642ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:01.535881Z","caller":"traceutil/trace.go:171","msg":"trace[722088907] linearizableReadLoop","detail":"{readStateIndex:25055; appliedIndex:25054; }","duration":"164.681456ms","start":"2026-05-29T02:54:01.37116Z","end":"2026-05-29T02:54:01.535842Z","steps":["trace[722088907] 'read index received' (duration: 90.912651ms)","trace[722088907] 'applied index is now lower than readState.Index' (duration: 73.766625ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:54:01.535962Z","caller":"traceutil/trace.go:171","msg":"trace[391672390] transaction","detail":"{read_only:false; response_revision:24264; number_of_response:1; }","duration":"237.959751ms","start":"2026-05-29T02:54:01.297966Z","end":"2026-05-29T02:54:01.535926Z","steps":["trace[391672390] 'process raft request' (duration: 164.088883ms)","trace[391672390] 'compare' (duration: 73.606453ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:54:01.536191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.034443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-29T02:54:01.53624Z","caller":"traceutil/trace.go:171","msg":"trace[373411164] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24264; }","duration":"165.143215ms","start":"2026-05-29T02:54:01.371084Z","end":"2026-05-29T02:54:01.536227Z","steps":["trace[373411164] 'agreement among raft nodes before linearized reading' (duration: 164.917981ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:01.53693Z","caller":"traceutil/trace.go:171","msg":"trace[1623351919] transaction","detail":"{read_only:false; response_revision:24265; number_of_response:1; }","duration":"153.658315ms","start":"2026-05-29T02:54:01.38325Z","end":"2026-05-29T02:54:01.536908Z","steps":["trace[1623351919] 'process raft request' (duration: 153.524252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:01.75205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.550046ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-29T02:54:01.752191Z","caller":"traceutil/trace.go:171","msg":"trace[479258805] linearizableReadLoop","detail":"{readStateIndex:25057; appliedIndex:25055; }","duration":"216.208313ms","start":"2026-05-29T02:54:01.535962Z","end":"2026-05-29T02:54:01.75217Z","steps":["trace[479258805] 'read index received' (duration: 807.926µs)","trace[479258805] 'applied index is now lower than readState.Index' (duration: 215.398997ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:54:01.752265Z","caller":"traceutil/trace.go:171","msg":"trace[1889489304] transaction","detail":"{read_only:false; response_revision:24266; number_of_response:1; }","duration":"273.650201ms","start":"2026-05-29T02:54:01.478603Z","end":"2026-05-29T02:54:01.752254Z","steps":["trace[1889489304] 'process raft request' (duration: 166.799949ms)","trace[1889489304] 'compare' (duration: 106.274201ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:54:01.7524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.363493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-29T02:54:01.75245Z","caller":"traceutil/trace.go:171","msg":"trace[358942631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24266; }","duration":"261.416354ms","start":"2026-05-29T02:54:01.491022Z","end":"2026-05-29T02:54:01.752438Z","steps":["trace[358942631] 'agreement among raft nodes before linearized reading' (duration: 261.22602ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:01.758644Z","caller":"traceutil/trace.go:171","msg":"trace[687219266] transaction","detail":"{read_only:false; response_revision:24267; number_of_response:1; }","duration":"216.980659ms","start":"2026-05-29T02:54:01.541647Z","end":"2026-05-29T02:54:01.758627Z","steps":["trace[687219266] 'process raft request' (duration: 216.821246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:01.758669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.391562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-29T02:54:01.758723Z","caller":"traceutil/trace.go:171","msg":"trace[1592178608] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:24267; }","duration":"176.500645ms","start":"2026-05-29T02:54:01.582207Z","end":"2026-05-29T02:54:01.758707Z","steps":["trace[1592178608] 'agreement among raft nodes before linearized reading' (duration: 176.373262ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:01.91868Z","caller":"traceutil/trace.go:171","msg":"trace[829080202] transaction","detail":"{read_only:false; response_revision:24269; number_of_response:1; }","duration":"156.556762ms","start":"2026-05-29T02:54:01.762092Z","end":"2026-05-29T02:54:01.918649Z","steps":["trace[829080202] 'process raft request' (duration: 156.45126ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:01.918862Z","caller":"traceutil/trace.go:171","msg":"trace[1943903742] transaction","detail":"{read_only:false; response_revision:24268; number_of_response:1; }","duration":"161.319107ms","start":"2026-05-29T02:54:01.75751Z","end":"2026-05-29T02:54:01.918829Z","steps":["trace[1943903742] 'process raft request' (duration: 137.815645ms)","trace[1943903742] 'compare' (duration: 23.047623ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:54:01.919303Z","caller":"traceutil/trace.go:171","msg":"trace[17142949] transaction","detail":"{read_only:false; response_revision:24270; number_of_response:1; }","duration":"156.062271ms","start":"2026-05-29T02:54:01.76265Z","end":"2026-05-29T02:54:01.918712Z","steps":["trace[17142949] 'process raft request' (duration: 155.950089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:02.190209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.065555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-29T02:54:02.190277Z","caller":"traceutil/trace.go:171","msg":"trace[1048743827] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:24270; }","duration":"105.227038ms","start":"2026-05-29T02:54:02.085034Z","end":"2026-05-29T02:54:02.190261Z","steps":["trace[1048743827] 'count revisions from in-memory index tree' (duration: 104.975623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:02.625588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.557096ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-29T02:54:02.625936Z","caller":"traceutil/trace.go:171","msg":"trace[445604122] linearizableReadLoop","detail":"{readStateIndex:25063; appliedIndex:25062; }","duration":"225.640164ms","start":"2026-05-29T02:54:02.400222Z","end":"2026-05-29T02:54:02.625862Z","steps":["trace[445604122] 'read index received' (duration: 118.903964ms)","trace[445604122] 'applied index is now lower than readState.Index' (duration: 106.7342ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:54:02.626186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.949919ms","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-05-29T02:54:02.626265Z","caller":"traceutil/trace.go:171","msg":"trace[1238664600] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24271; }","duration":"226.087732ms","start":"2026-05-29T02:54:02.400159Z","end":"2026-05-29T02:54:02.626247Z","steps":["trace[1238664600] 'agreement among raft nodes before linearized reading' (duration: 225.828167ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:02.632957Z","caller":"traceutil/trace.go:171","msg":"trace[12931205] transaction","detail":"{read_only:false; response_revision:24272; number_of_response:1; }","duration":"165.732407ms","start":"2026-05-29T02:54:02.467199Z","end":"2026-05-29T02:54:02.632931Z","steps":["trace[12931205] 'process raft request' (duration: 165.444122ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:03.44433Z","caller":"traceutil/trace.go:171","msg":"trace[2117765549] linearizableReadLoop","detail":"{readStateIndex:25069; appliedIndex:25068; }","duration":"183.068346ms","start":"2026-05-29T02:54:03.261237Z","end":"2026-05-29T02:54:03.444305Z","steps":["trace[2117765549] 'read index received' (duration: 182.910173ms)","trace[2117765549] 'applied index is now lower than readState.Index' (duration: 156.783µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:54:03.444476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.218609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-29T02:54:03.444447Z","caller":"traceutil/trace.go:171","msg":"trace[1437055483] transaction","detail":"{read_only:false; response_revision:24277; number_of_response:1; }","duration":"183.332241ms","start":"2026-05-29T02:54:03.261072Z","end":"2026-05-29T02:54:03.444404Z","steps":["trace[1437055483] 'process raft request' (duration: 183.027025ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:03.444515Z","caller":"traceutil/trace.go:171","msg":"trace[102829347] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24277; }","duration":"183.306ms","start":"2026-05-29T02:54:03.261198Z","end":"2026-05-29T02:54:03.444504Z","steps":["trace[102829347] 'agreement among raft nodes before linearized reading' (duration: 183.226469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:03.66109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.51625ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-29T02:54:03.661372Z","caller":"traceutil/trace.go:171","msg":"trace[1183956931] transaction","detail":"{read_only:false; response_revision:24278; number_of_response:1; }","duration":"371.833156ms","start":"2026-05-29T02:54:03.289502Z","end":"2026-05-29T02:54:03.661335Z","steps":["trace[1183956931] 'process raft request' (duration: 225.01257ms)","trace[1183956931] 'compare' (duration: 146.291905ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:54:03.661433Z","caller":"traceutil/trace.go:171","msg":"trace[477253094] linearizableReadLoop","detail":"{readStateIndex:25070; appliedIndex:25069; }","duration":"217.02612ms","start":"2026-05-29T02:54:03.444393Z","end":"2026-05-29T02:54:03.661419Z","steps":["trace[477253094] 'read index received' (duration: 70.206364ms)","trace[477253094] 'applied index is now lower than readState.Index' (duration: 146.819026ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:54:03.661548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:54:03.289482Z","time spent":"371.968159ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:54:03.661737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.712582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-29T02:54:03.661762Z","caller":"traceutil/trace.go:171","msg":"trace[1273910321] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:24279; }","duration":"273.775602ms","start":"2026-05-29T02:54:03.38798Z","end":"2026-05-29T02:54:03.661755Z","steps":["trace[1273910321] 'agreement among raft nodes before linearized reading' (duration: 273.521866ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:03.66192Z","caller":"traceutil/trace.go:171","msg":"trace[1951640997] transaction","detail":"{read_only:false; response_revision:24279; number_of_response:1; }","duration":"183.264321ms","start":"2026-05-29T02:54:03.478611Z","end":"2026-05-29T02:54:03.661875Z","steps":["trace[1951640997] 'process raft request' (duration: 182.557116ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:03.662005Z","caller":"traceutil/trace.go:171","msg":"trace[1803430594] transaction","detail":"{read_only:false; response_revision:24280; number_of_response:1; }","duration":"120.244022ms","start":"2026-05-29T02:54:03.54174Z","end":"2026-05-29T02:54:03.661984Z","steps":["trace[1803430594] 'process raft request' (duration: 120.002857ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:03.662001Z","caller":"traceutil/trace.go:171","msg":"trace[1217074824] transaction","detail":"{read_only:false; response_revision:24281; number_of_response:1; }","duration":"119.193681ms","start":"2026-05-29T02:54:03.542785Z","end":"2026-05-29T02:54:03.661978Z","steps":["trace[1217074824] 'process raft request' (duration: 119.14107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:03.662077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.46502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-29T02:54:03.662174Z","caller":"traceutil/trace.go:171","msg":"trace[1861496617] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24281; }","duration":"216.624663ms","start":"2026-05-29T02:54:03.445526Z","end":"2026-05-29T02:54:03.662151Z","steps":["trace[1861496617] 'agreement among raft nodes before linearized reading' (duration: 216.441469ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:03.959388Z","caller":"traceutil/trace.go:171","msg":"trace[1918005405] linearizableReadLoop","detail":"{readStateIndex:25074; appliedIndex:25073; }","duration":"197.399326ms","start":"2026-05-29T02:54:03.761963Z","end":"2026-05-29T02:54:03.959363Z","steps":["trace[1918005405] 'read index received' (duration: 197.189161ms)","trace[1918005405] 'applied index is now lower than readState.Index' (duration: 209.334µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:54:03.959727Z","caller":"traceutil/trace.go:171","msg":"trace[718596789] transaction","detail":"{read_only:false; response_revision:24282; number_of_response:1; }","duration":"200.415146ms","start":"2026-05-29T02:54:03.759299Z","end":"2026-05-29T02:54:03.959714Z","steps":["trace[718596789] 'process raft request' (duration: 199.918897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:03.96001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.044398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-29T02:54:03.960044Z","caller":"traceutil/trace.go:171","msg":"trace[2133319924] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24282; }","duration":"198.199131ms","start":"2026-05-29T02:54:03.761836Z","end":"2026-05-29T02:54:03.960035Z","steps":["trace[2133319924] 'agreement among raft nodes before linearized reading' (duration: 198.069808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:03.960215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.421116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-29T02:54:03.960244Z","caller":"traceutil/trace.go:171","msg":"trace[97076003] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:24282; }","duration":"132.502338ms","start":"2026-05-29T02:54:03.827733Z","end":"2026-05-29T02:54:03.960235Z","steps":["trace[97076003] 'agreement among raft nodes before linearized reading' (duration: 132.439817ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:04.494617Z","caller":"traceutil/trace.go:171","msg":"trace[664029562] transaction","detail":"{read_only:false; response_revision:24286; number_of_response:1; }","duration":"110.131287ms","start":"2026-05-29T02:54:04.384452Z","end":"2026-05-29T02:54:04.494584Z","steps":["trace[664029562] 'process raft request' (duration: 109.944594ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:05.018885Z","caller":"traceutil/trace.go:171","msg":"trace[315146362] transaction","detail":"{read_only:false; response_revision:24288; number_of_response:1; }","duration":"276.526278ms","start":"2026-05-29T02:54:04.742134Z","end":"2026-05-29T02:54:05.018661Z","steps":["trace[315146362] 'process raft request' (duration: 275.053458ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:05.041798Z","caller":"traceutil/trace.go:171","msg":"trace[1804067635] linearizableReadLoop","detail":"{readStateIndex:25081; appliedIndex:25079; }","duration":"232.93183ms","start":"2026-05-29T02:54:04.808838Z","end":"2026-05-29T02:54:05.041769Z","steps":["trace[1804067635] 'read index received' (duration: 208.295744ms)","trace[1804067635] 'applied index is now lower than readState.Index' (duration: 24.634576ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:54:05.041976Z","caller":"traceutil/trace.go:171","msg":"trace[18377728] transaction","detail":"{read_only:false; response_revision:24289; number_of_response:1; }","duration":"299.352477ms","start":"2026-05-29T02:54:04.742585Z","end":"2026-05-29T02:54:05.041937Z","steps":["trace[18377728] 'process raft request' (duration: 298.99675ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:05.042196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.318948ms","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-05-29T02:54:05.042292Z","caller":"traceutil/trace.go:171","msg":"trace[1566038637] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24289; }","duration":"233.466841ms","start":"2026-05-29T02:54:04.8088Z","end":"2026-05-29T02:54:05.042267Z","steps":["trace[1566038637] 'agreement among raft nodes before linearized reading' (duration: 233.162565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:05.35957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.020056ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-29T02:54:05.360231Z","caller":"traceutil/trace.go:171","msg":"trace[1954064623] transaction","detail":"{read_only:false; response_revision:24291; number_of_response:1; }","duration":"311.748997ms","start":"2026-05-29T02:54:05.04844Z","end":"2026-05-29T02:54:05.360189Z","steps":["trace[1954064623] 'process raft request' (duration: 311.38177ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:05.360285Z","caller":"traceutil/trace.go:171","msg":"trace[225354100] transaction","detail":"{read_only:false; response_revision:24290; number_of_response:1; }","duration":"313.670835ms","start":"2026-05-29T02:54:05.046544Z","end":"2026-05-29T02:54:05.360215Z","steps":["trace[225354100] 'process raft request' (duration: 132.867144ms)","trace[225354100] 'compare' (duration: 179.622177ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:54:05.360288Z","caller":"traceutil/trace.go:171","msg":"trace[25099654] transaction","detail":"{read_only:false; response_revision:24292; number_of_response:1; }","duration":"204.879425ms","start":"2026-05-29T02:54:05.155356Z","end":"2026-05-29T02:54:05.360235Z","steps":["trace[25099654] 'process raft request' (duration: 204.581989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:05.360426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:54:05.048411Z","time spent":"311.921411ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:54:05.360556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:54:05.046505Z","time spent":"313.921511ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:54:05.608774Z","caller":"traceutil/trace.go:171","msg":"trace[2062055057] transaction","detail":"{read_only:false; response_revision:24293; number_of_response:1; }","duration":"158.90468ms","start":"2026-05-29T02:54:05.449836Z","end":"2026-05-29T02:54:05.60874Z","steps":["trace[2062055057] 'process raft request' (duration: 158.721456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:05.931702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.327497ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-29T02:54:05.93196Z","caller":"traceutil/trace.go:171","msg":"trace[1961223313] transaction","detail":"{read_only:false; response_revision:24295; number_of_response:1; }","duration":"263.775031ms","start":"2026-05-29T02:54:05.668161Z","end":"2026-05-29T02:54:05.931936Z","steps":["trace[1961223313] 'process raft request' (duration: 263.69313ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:05.932206Z","caller":"traceutil/trace.go:171","msg":"trace[922497395] transaction","detail":"{read_only:false; response_revision:24294; number_of_response:1; }","duration":"264.017206ms","start":"2026-05-29T02:54:05.668119Z","end":"2026-05-29T02:54:05.932136Z","steps":["trace[922497395] 'process raft request' (duration: 113.121588ms)","trace[922497395] 'compare' (duration: 150.094593ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:54:05.990484Z","caller":"traceutil/trace.go:171","msg":"trace[2066347101] transaction","detail":"{read_only:false; response_revision:24296; number_of_response:1; }","duration":"315.942742ms","start":"2026-05-29T02:54:05.674513Z","end":"2026-05-29T02:54:05.990456Z","steps":["trace[2066347101] 'process raft request' (duration: 315.589525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:05.99072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:54:05.67448Z","time spent":"316.102865ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:54:05.991857Z","caller":"traceutil/trace.go:171","msg":"trace[664949073] linearizableReadLoop","detail":"{readStateIndex:25089; appliedIndex:25087; }","duration":"133.263133ms","start":"2026-05-29T02:54:05.858576Z","end":"2026-05-29T02:54:05.991839Z","steps":["trace[664949073] 'read index received' (duration: 131.58508ms)","trace[664949073] 'applied index is now lower than readState.Index' (duration: 1.676723ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:54:05.991917Z","caller":"traceutil/trace.go:171","msg":"trace[175692616] transaction","detail":"{read_only:false; response_revision:24297; number_of_response:1; }","duration":"212.769684ms","start":"2026-05-29T02:54:05.779116Z","end":"2026-05-29T02:54:05.991885Z","steps":["trace[175692616] 'process raft request' (duration: 212.005529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:05.992038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.442726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-29T02:54:05.992168Z","caller":"traceutil/trace.go:171","msg":"trace[1393637110] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:24297; }","duration":"133.595799ms","start":"2026-05-29T02:54:05.858544Z","end":"2026-05-29T02:54:05.99214Z","steps":["trace[1393637110] 'agreement among raft nodes before linearized reading' (duration: 133.363245ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:06.239372Z","caller":"traceutil/trace.go:171","msg":"trace[250143881] linearizableReadLoop","detail":"{readStateIndex:25090; appliedIndex:25089; }","duration":"247.404362ms","start":"2026-05-29T02:54:05.991949Z","end":"2026-05-29T02:54:06.239353Z","steps":["trace[250143881] 'read index received' (duration: 247.199278ms)","trace[250143881] 'applied index is now lower than readState.Index' (duration: 204.234µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:54:06.239507Z","caller":"traceutil/trace.go:171","msg":"trace[328726482] transaction","detail":"{read_only:false; response_revision:24298; number_of_response:1; }","duration":"272.228751ms","start":"2026-05-29T02:54:05.967243Z","end":"2026-05-29T02:54:06.239471Z","steps":["trace[328726482] 'process raft request' (duration: 271.926665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:06.239575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.813022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-29T02:54:06.239617Z","caller":"traceutil/trace.go:171","msg":"trace[273841742] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24298; }","duration":"262.883484ms","start":"2026-05-29T02:54:05.976721Z","end":"2026-05-29T02:54:06.239604Z","steps":["trace[273841742] 'agreement among raft nodes before linearized reading' (duration: 262.733601ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:06.239794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.584731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-29T02:54:06.239899Z","caller":"traceutil/trace.go:171","msg":"trace[1551717050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24298; }","duration":"211.732684ms","start":"2026-05-29T02:54:06.028151Z","end":"2026-05-29T02:54:06.239884Z","steps":["trace[1551717050] 'agreement among raft nodes before linearized reading' (duration: 211.389418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:06.23991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.148992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-29T02:54:06.240032Z","caller":"traceutil/trace.go:171","msg":"trace[1396503219] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24298; }","duration":"211.355937ms","start":"2026-05-29T02:54:06.028645Z","end":"2026-05-29T02:54:06.240001Z","steps":["trace[1396503219] 'agreement among raft nodes before linearized reading' (duration: 210.985529ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:20.227399Z","caller":"traceutil/trace.go:171","msg":"trace[184760730] linearizableReadLoop","detail":"{readStateIndex:25193; appliedIndex:25192; }","duration":"240.242617ms","start":"2026-05-29T02:54:19.98711Z","end":"2026-05-29T02:54:20.227353Z","steps":["trace[184760730] 'read index received' (duration: 239.494873ms)","trace[184760730] 'applied index is now lower than readState.Index' (duration: 745.774µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:54:20.227488Z","caller":"traceutil/trace.go:171","msg":"trace[894728837] transaction","detail":"{read_only:false; response_revision:24398; number_of_response:1; }","duration":"240.746818ms","start":"2026-05-29T02:54:19.986674Z","end":"2026-05-29T02:54:20.227421Z","steps":["trace[894728837] 'process raft request' (duration: 239.976192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:20.227599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.470273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-29T02:54:20.227703Z","caller":"traceutil/trace.go:171","msg":"trace[1348364245] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:24398; }","duration":"240.625285ms","start":"2026-05-29T02:54:19.987051Z","end":"2026-05-29T02:54:20.227676Z","steps":["trace[1348364245] 'agreement among raft nodes before linearized reading' (duration: 240.426871ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:20.519953Z","caller":"traceutil/trace.go:171","msg":"trace[251218168] transaction","detail":"{read_only:false; response_revision:24402; number_of_response:1; }","duration":"226.801237ms","start":"2026-05-29T02:54:20.293115Z","end":"2026-05-29T02:54:20.519916Z","steps":["trace[251218168] 'process raft request' (duration: 226.756096ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:20.52045Z","caller":"traceutil/trace.go:171","msg":"trace[1259169961] transaction","detail":"{read_only:false; response_revision:24400; number_of_response:1; }","duration":"473.085896ms","start":"2026-05-29T02:54:20.047346Z","end":"2026-05-29T02:54:20.520431Z","steps":["trace[1259169961] 'process raft request' (duration: 472.441982ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:20.520451Z","caller":"traceutil/trace.go:171","msg":"trace[1077164193] transaction","detail":"{read_only:false; response_revision:24401; number_of_response:1; }","duration":"457.135585ms","start":"2026-05-29T02:54:20.063281Z","end":"2026-05-29T02:54:20.520416Z","steps":["trace[1077164193] 'process raft request' (duration: 456.548802ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:20.520458Z","caller":"traceutil/trace.go:171","msg":"trace[1577164328] transaction","detail":"{read_only:false; response_revision:24399; number_of_response:1; }","duration":"533.243488ms","start":"2026-05-29T02:54:19.987184Z","end":"2026-05-29T02:54:20.520427Z","steps":["trace[1577164328] 'process raft request' (duration: 515.821797ms)","trace[1577164328] 'compare' (duration: 16.600244ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:54:20.520563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.019471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-29T02:54:20.520608Z","caller":"traceutil/trace.go:171","msg":"trace[105092] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:24402; }","duration":"516.117423ms","start":"2026-05-29T02:54:20.004482Z","end":"2026-05-29T02:54:20.520599Z","steps":["trace[105092] 'agreement among raft nodes before linearized reading' (duration: 516.013571ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:20.520454Z","caller":"traceutil/trace.go:171","msg":"trace[215252421] linearizableReadLoop","detail":"{readStateIndex:25196; appliedIndex:25193; }","duration":"292.944619ms","start":"2026-05-29T02:54:20.227486Z","end":"2026-05-29T02:54:20.520431Z","steps":["trace[215252421] 'read index received' (duration: 275.548948ms)","trace[215252421] 'applied index is now lower than readState.Index' (duration: 17.393931ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:54:20.520635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:54:20.004461Z","time spent":"516.166924ms","remote":"127.0.0.1:58868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true "} {"level":"warn","ts":"2026-05-29T02:54:20.520654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:54:20.047306Z","time spent":"473.197908ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:54:20.520631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.262244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-29T02:54:20.520609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:54:19.987137Z","time spent":"533.379851ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:54:20.520697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:54:20.063266Z","time spent":"457.242518ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:54:20.520735Z","caller":"traceutil/trace.go:171","msg":"trace[1193529571] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:24402; }","duration":"502.431957ms","start":"2026-05-29T02:54:20.018273Z","end":"2026-05-29T02:54:20.520705Z","steps":["trace[1193529571] 'agreement among raft nodes before linearized reading' (duration: 502.183943ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:20.520805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:54:20.018257Z","time spent":"502.520209ms","remote":"127.0.0.1:60004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true "} {"level":"warn","ts":"2026-05-29T02:54:20.520847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.704753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-29T02:54:20.520903Z","caller":"traceutil/trace.go:171","msg":"trace[1086842997] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:24402; }","duration":"284.820255ms","start":"2026-05-29T02:54:20.236065Z","end":"2026-05-29T02:54:20.520885Z","steps":["trace[1086842997] 'agreement among raft nodes before linearized reading' (duration: 284.716453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:20.520921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.263142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-05-29T02:54:20.520934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.773772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-29T02:54:20.52094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.861795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-29T02:54:20.520991Z","caller":"traceutil/trace.go:171","msg":"trace[102047387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24402; }","duration":"196.848614ms","start":"2026-05-29T02:54:20.32413Z","end":"2026-05-29T02:54:20.520979Z","steps":["trace[102047387] 'agreement among raft nodes before linearized reading' (duration: 196.65939ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:20.521009Z","caller":"traceutil/trace.go:171","msg":"trace[218654120] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24402; }","duration":"202.970117ms","start":"2026-05-29T02:54:20.318019Z","end":"2026-05-29T02:54:20.520989Z","steps":["trace[218654120] 'agreement among raft nodes before linearized reading' (duration: 202.776963ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:20.520958Z","caller":"traceutil/trace.go:171","msg":"trace[1753652434] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24402; }","duration":"191.314403ms","start":"2026-05-29T02:54:20.329628Z","end":"2026-05-29T02:54:20.520943Z","steps":["trace[1753652434] 'agreement among raft nodes before linearized reading' (duration: 191.191901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:20.520911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.082077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-29T02:54:20.521184Z","caller":"traceutil/trace.go:171","msg":"trace[1900979220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24402; }","duration":"261.428424ms","start":"2026-05-29T02:54:20.259745Z","end":"2026-05-29T02:54:20.521173Z","steps":["trace[1900979220] 'agreement among raft nodes before linearized reading' (duration: 261.079487ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:20.983294Z","caller":"traceutil/trace.go:171","msg":"trace[1638579634] linearizableReadLoop","detail":"{readStateIndex:25198; appliedIndex:25197; }","duration":"455.508692ms","start":"2026-05-29T02:54:20.527755Z","end":"2026-05-29T02:54:20.983264Z","steps":["trace[1638579634] 'read index received' (duration: 406.466754ms)","trace[1638579634] 'applied index is now lower than readState.Index' (duration: 49.040538ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:54:20.983376Z","caller":"traceutil/trace.go:171","msg":"trace[2070872612] transaction","detail":"{read_only:false; response_revision:24403; number_of_response:1; }","duration":"459.695296ms","start":"2026-05-29T02:54:20.523639Z","end":"2026-05-29T02:54:20.983334Z","steps":["trace[2070872612] 'process raft request' (duration: 410.568666ms)","trace[2070872612] 'compare' (duration: 48.689731ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:54:20.983474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.684666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-29T02:54:20.983525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:54:20.523607Z","time spent":"459.846129ms","remote":"127.0.0.1:40358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1560,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T02:54:20.983557Z","caller":"traceutil/trace.go:171","msg":"trace[581713859] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:24403; }","duration":"455.824219ms","start":"2026-05-29T02:54:20.527709Z","end":"2026-05-29T02:54:20.983533Z","steps":["trace[581713859] 'agreement among raft nodes before linearized reading' (duration: 455.644425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:20.983616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:54:20.527689Z","time spent":"455.90957ms","remote":"127.0.0.1:59966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true "} {"level":"info","ts":"2026-05-29T02:54:20.984307Z","caller":"traceutil/trace.go:171","msg":"trace[1865422273] transaction","detail":"{read_only:false; response_revision:24404; number_of_response:1; }","duration":"456.515833ms","start":"2026-05-29T02:54:20.527772Z","end":"2026-05-29T02:54:20.984288Z","steps":["trace[1865422273] 'process raft request' (duration: 456.321409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:20.984404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:54:20.527757Z","time spent":"456.597184ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:54:20.984712Z","caller":"traceutil/trace.go:171","msg":"trace[1302529485] transaction","detail":"{read_only:false; response_revision:24405; number_of_response:1; }","duration":"456.796828ms","start":"2026-05-29T02:54:20.527869Z","end":"2026-05-29T02:54:20.984666Z","steps":["trace[1302529485] 'process raft request' (duration: 456.37285ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:20.984904Z","caller":"traceutil/trace.go:171","msg":"trace[1410097770] transaction","detail":"{read_only:false; response_revision:24407; number_of_response:1; }","duration":"144.694152ms","start":"2026-05-29T02:54:20.840183Z","end":"2026-05-29T02:54:20.984877Z","steps":["trace[1410097770] 'process raft request' (duration: 144.543459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:20.984945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:54:20.527852Z","time spent":"456.964481ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:54:20.984975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.037961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-29T02:54:20.985047Z","caller":"traceutil/trace.go:171","msg":"trace[1187858581] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:24407; }","duration":"228.150543ms","start":"2026-05-29T02:54:20.756876Z","end":"2026-05-29T02:54:20.985027Z","steps":["trace[1187858581] 'agreement among raft nodes before linearized reading' (duration: 228.032131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:20.985076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.576144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-29T02:54:20.985202Z","caller":"traceutil/trace.go:171","msg":"trace[1124056405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24407; }","duration":"390.766388ms","start":"2026-05-29T02:54:20.594404Z","end":"2026-05-29T02:54:20.98517Z","steps":["trace[1124056405] 'agreement among raft nodes before linearized reading' (duration: 390.404941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:20.985303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:54:20.59438Z","time spent":"390.910892ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-29T02:54:20.985604Z","caller":"traceutil/trace.go:171","msg":"trace[58684975] transaction","detail":"{read_only:false; response_revision:24406; number_of_response:1; }","duration":"456.92026ms","start":"2026-05-29T02:54:20.527891Z","end":"2026-05-29T02:54:20.984811Z","steps":["trace[58684975] 'process raft request' (duration: 456.618034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:20.985817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:54:20.527852Z","time spent":"457.87612ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:54:21.4675Z","caller":"traceutil/trace.go:171","msg":"trace[843528484] transaction","detail":"{read_only:false; response_revision:24408; number_of_response:1; }","duration":"476.521755ms","start":"2026-05-29T02:54:20.990939Z","end":"2026-05-29T02:54:21.467461Z","steps":["trace[843528484] 'process raft request' (duration: 476.307321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:21.467709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:54:20.990917Z","time spent":"476.675028ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:54:21.812842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.833635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-29T02:54:21.81329Z","caller":"traceutil/trace.go:171","msg":"trace[441073998] transaction","detail":"{read_only:false; response_revision:24411; number_of_response:1; }","duration":"132.337305ms","start":"2026-05-29T02:54:21.680927Z","end":"2026-05-29T02:54:21.813264Z","steps":["trace[441073998] 'process raft request' (duration: 132.270583ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:21.813804Z","caller":"traceutil/trace.go:171","msg":"trace[495440236] linearizableReadLoop","detail":"{readStateIndex:25204; appliedIndex:25202; }","duration":"553.669728ms","start":"2026-05-29T02:54:21.260107Z","end":"2026-05-29T02:54:21.813776Z","steps":["trace[495440236] 'read index received' (duration: 207.147851ms)","trace[495440236] 'applied index is now lower than readState.Index' (duration: 346.519677ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:54:21.813809Z","caller":"traceutil/trace.go:171","msg":"trace[1369926453] transaction","detail":"{read_only:false; response_revision:24410; number_of_response:1; }","duration":"377.120853ms","start":"2026-05-29T02:54:21.436651Z","end":"2026-05-29T02:54:21.813772Z","steps":["trace[1369926453] 'process raft request' (duration: 376.391318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:21.813989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.871211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-29T02:54:21.814044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:54:21.436614Z","time spent":"377.339667ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:54:21.814067Z","caller":"traceutil/trace.go:171","msg":"trace[491615957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24411; }","duration":"553.999454ms","start":"2026-05-29T02:54:21.260047Z","end":"2026-05-29T02:54:21.814047Z","steps":["trace[491615957] 'agreement among raft nodes before linearized reading' (duration: 553.867562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:21.814094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.889578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-29T02:54:21.81411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:54:21.260027Z","time spent":"554.074846ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-29T02:54:21.814135Z","caller":"traceutil/trace.go:171","msg":"trace[1259684638] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24411; }","duration":"372.99536ms","start":"2026-05-29T02:54:21.441129Z","end":"2026-05-29T02:54:21.814124Z","steps":["trace[1259684638] 'agreement among raft nodes before linearized reading' (duration: 372.824346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:21.814172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:54:21.44111Z","time spent":"373.052511ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T02:54:21.813838Z","caller":"traceutil/trace.go:171","msg":"trace[400485131] transaction","detail":"{read_only:false; response_revision:24409; number_of_response:1; }","duration":"816.558481ms","start":"2026-05-29T02:54:20.997241Z","end":"2026-05-29T02:54:21.8138Z","steps":["trace[400485131] 'process raft request' (duration: 532.603154ms)","trace[400485131] 'compare' (duration: 282.354266ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:54:21.814411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:54:20.997227Z","time spent":"817.058661ms","remote":"127.0.0.1:40358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1873,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:54:21.814729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.683434ms","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-05-29T02:54:21.81486Z","caller":"traceutil/trace.go:171","msg":"trace[583353820] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24411; }","duration":"372.873608ms","start":"2026-05-29T02:54:21.441955Z","end":"2026-05-29T02:54:21.814829Z","steps":["trace[583353820] 'agreement among raft nodes before linearized reading' (duration: 372.101061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:21.816097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:54:21.44193Z","time spent":"374.145003ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-29T02:54:22.137282Z","caller":"traceutil/trace.go:171","msg":"trace[250690413] transaction","detail":"{read_only:false; response_revision:24412; number_of_response:1; }","duration":"316.721858ms","start":"2026-05-29T02:54:21.820527Z","end":"2026-05-29T02:54:22.137249Z","steps":["trace[250690413] 'process raft request' (duration: 286.651282ms)","trace[250690413] 'compare' (duration: 29.938933ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:54:22.137849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:54:21.820504Z","time spent":"317.266468ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:54:22.181335Z","caller":"traceutil/trace.go:171","msg":"trace[687658650] linearizableReadLoop","detail":"{readStateIndex:25209; appliedIndex:25206; }","duration":"233.569243ms","start":"2026-05-29T02:54:21.947736Z","end":"2026-05-29T02:54:22.181305Z","steps":["trace[687658650] 'read index received' (duration: 159.468221ms)","trace[687658650] 'applied index is now lower than readState.Index' (duration: 74.099702ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:54:22.181445Z","caller":"traceutil/trace.go:171","msg":"trace[1355748098] transaction","detail":"{read_only:false; response_revision:24414; number_of_response:1; }","duration":"351.442066ms","start":"2026-05-29T02:54:21.829964Z","end":"2026-05-29T02:54:22.181406Z","steps":["trace[1355748098] 'process raft request' (duration: 351.272983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:22.181497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.738487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/securitygroups/\" range_end:\"/registry/openstack.k-orc.cloud/securitygroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-29T02:54:22.181538Z","caller":"traceutil/trace.go:171","msg":"trace[1924335354] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/securitygroups/; range_end:/registry/openstack.k-orc.cloud/securitygroups0; response_count:0; response_revision:24414; }","duration":"233.84352ms","start":"2026-05-29T02:54:21.94768Z","end":"2026-05-29T02:54:22.181524Z","steps":["trace[1924335354] 'agreement among raft nodes before linearized reading' (duration: 233.724516ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:22.181616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:54:21.829931Z","time spent":"351.58524ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:54:22.181964Z","caller":"traceutil/trace.go:171","msg":"trace[1113118244] transaction","detail":"{read_only:false; response_revision:24413; number_of_response:1; }","duration":"357.013708ms","start":"2026-05-29T02:54:21.824348Z","end":"2026-05-29T02:54:22.181361Z","steps":["trace[1113118244] 'process raft request' (duration: 356.687462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:22.182182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:54:21.824318Z","time spent":"357.740624ms","remote":"127.0.0.1:40358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2720,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:54:22.476687Z","caller":"traceutil/trace.go:171","msg":"trace[69653874] transaction","detail":"{read_only:false; response_revision:24417; number_of_response:1; }","duration":"265.779862ms","start":"2026-05-29T02:54:22.210857Z","end":"2026-05-29T02:54:22.476637Z","steps":["trace[69653874] 'process raft request' (duration: 265.334883ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:22.476935Z","caller":"traceutil/trace.go:171","msg":"trace[1407709056] transaction","detail":"{read_only:false; response_revision:24418; number_of_response:1; }","duration":"264.732502ms","start":"2026-05-29T02:54:22.212157Z","end":"2026-05-29T02:54:22.47689Z","steps":["trace[1407709056] 'process raft request' (duration: 264.312273ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:22.558429Z","caller":"traceutil/trace.go:171","msg":"trace[1832797750] transaction","detail":"{read_only:false; response_revision:24419; number_of_response:1; }","duration":"325.484313ms","start":"2026-05-29T02:54:22.232908Z","end":"2026-05-29T02:54:22.558392Z","steps":["trace[1832797750] 'process raft request' (duration: 325.019724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:22.558633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:54:22.232894Z","time spent":"325.643316ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:54:22.583764Z","caller":"traceutil/trace.go:171","msg":"trace[280638622] linearizableReadLoop","detail":"{readStateIndex:25215; appliedIndex:25212; }","duration":"277.176402ms","start":"2026-05-29T02:54:22.306558Z","end":"2026-05-29T02:54:22.583735Z","steps":["trace[280638622] 'read index received' (duration: 170.149097ms)","trace[280638622] 'applied index is now lower than readState.Index' (duration: 107.025885ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:54:22.584016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.421386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-29T02:54:22.584066Z","caller":"traceutil/trace.go:171","msg":"trace[123277427] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24419; }","duration":"277.550509ms","start":"2026-05-29T02:54:22.306501Z","end":"2026-05-29T02:54:22.584051Z","steps":["trace[123277427] 'agreement among raft nodes before linearized reading' (duration: 277.431457ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:22.721692Z","caller":"traceutil/trace.go:171","msg":"trace[2068148963] transaction","detail":"{read_only:false; response_revision:24420; number_of_response:1; }","duration":"243.531034ms","start":"2026-05-29T02:54:22.478122Z","end":"2026-05-29T02:54:22.721653Z","steps":["trace[2068148963] 'process raft request' (duration: 243.212637ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:22.721824Z","caller":"traceutil/trace.go:171","msg":"trace[2044289023] linearizableReadLoop","detail":"{readStateIndex:25221; appliedIndex:25216; }","duration":"137.910567ms","start":"2026-05-29T02:54:22.583895Z","end":"2026-05-29T02:54:22.721806Z","steps":["trace[2044289023] 'read index received' (duration: 137.301905ms)","trace[2044289023] 'applied index is now lower than readState.Index' (duration: 607.432µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:54:22.721966Z","caller":"traceutil/trace.go:171","msg":"trace[1083206692] transaction","detail":"{read_only:false; response_revision:24421; number_of_response:1; }","duration":"242.682236ms","start":"2026-05-29T02:54:22.479271Z","end":"2026-05-29T02:54:22.721954Z","steps":["trace[1083206692] 'process raft request' (duration: 242.281358ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:22.722036Z","caller":"traceutil/trace.go:171","msg":"trace[1173550193] transaction","detail":"{read_only:false; response_revision:24424; number_of_response:1; }","duration":"193.259001ms","start":"2026-05-29T02:54:22.528726Z","end":"2026-05-29T02:54:22.721985Z","steps":["trace[1173550193] 'process raft request' (duration: 193.037427ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:22.72207Z","caller":"traceutil/trace.go:171","msg":"trace[1605818871] transaction","detail":"{read_only:false; response_revision:24422; number_of_response:1; }","duration":"194.619968ms","start":"2026-05-29T02:54:22.527405Z","end":"2026-05-29T02:54:22.722025Z","steps":["trace[1605818871] 'process raft request' (duration: 194.22181ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:22.722224Z","caller":"traceutil/trace.go:171","msg":"trace[80341812] transaction","detail":"{read_only:false; response_revision:24423; number_of_response:1; }","duration":"193.64231ms","start":"2026-05-29T02:54:22.528517Z","end":"2026-05-29T02:54:22.722159Z","steps":["trace[80341812] 'process raft request' (duration: 193.19261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:22.72236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.101035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-29T02:54:22.72242Z","caller":"traceutil/trace.go:171","msg":"trace[1627977650] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:24424; }","duration":"386.191347ms","start":"2026-05-29T02:54:22.336215Z","end":"2026-05-29T02:54:22.722406Z","steps":["trace[1627977650] 'agreement among raft nodes before linearized reading' (duration: 386.065314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:22.722468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:54:22.336177Z","time spent":"386.280789ms","remote":"127.0.0.1:40482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"warn","ts":"2026-05-29T02:54:22.722361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.336472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-29T02:54:22.722535Z","caller":"traceutil/trace.go:171","msg":"trace[3088767] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:24424; }","duration":"109.549636ms","start":"2026-05-29T02:54:22.612971Z","end":"2026-05-29T02:54:22.72252Z","steps":["trace[3088767] 'agreement among raft nodes before linearized reading' (duration: 109.307741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:22.722216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.894906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-29T02:54:22.724184Z","caller":"traceutil/trace.go:171","msg":"trace[640577603] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24424; }","duration":"132.901106ms","start":"2026-05-29T02:54:22.591243Z","end":"2026-05-29T02:54:22.724144Z","steps":["trace[640577603] 'agreement among raft nodes before linearized reading' (duration: 130.787623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:22.98248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.760157ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-05-29T02:54:22.982666Z","caller":"traceutil/trace.go:171","msg":"trace[1139312897] transaction","detail":"{read_only:false; response_revision:24425; number_of_response:1; }","duration":"255.645648ms","start":"2026-05-29T02:54:22.726999Z","end":"2026-05-29T02:54:22.982644Z","steps":["trace[1139312897] 'process raft request' (duration: 86.630155ms)","trace[1139312897] 'compare' (duration: 168.578884ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:54:23.030896Z","caller":"traceutil/trace.go:171","msg":"trace[1840001530] transaction","detail":"{read_only:false; response_revision:24426; number_of_response:1; }","duration":"302.541401ms","start":"2026-05-29T02:54:22.728323Z","end":"2026-05-29T02:54:23.030865Z","steps":["trace[1840001530] 'process raft request' (duration: 302.289466ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:23.03092Z","caller":"traceutil/trace.go:171","msg":"trace[1786661364] linearizableReadLoop","detail":"{readStateIndex:25225; appliedIndex:25221; }","duration":"300.102913ms","start":"2026-05-29T02:54:22.730791Z","end":"2026-05-29T02:54:23.030894Z","steps":["trace[1786661364] 'read index received' (duration: 82.812427ms)","trace[1786661364] 'applied index is now lower than readState.Index' (duration: 217.289246ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:54:23.030947Z","caller":"traceutil/trace.go:171","msg":"trace[1081731336] transaction","detail":"{read_only:false; response_revision:24427; number_of_response:1; }","duration":"302.552372ms","start":"2026-05-29T02:54:22.728368Z","end":"2026-05-29T02:54:23.03092Z","steps":["trace[1081731336] 'process raft request' (duration: 302.42332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:23.03105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:54:22.728301Z","time spent":"302.680244ms","remote":"127.0.0.1:36628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":800,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T02:54:23.031105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:54:22.728212Z","time spent":"302.821117ms","remote":"127.0.0.1:36796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1133,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T02:54:23.031148Z","caller":"traceutil/trace.go:171","msg":"trace[906080530] transaction","detail":"{read_only:false; response_revision:24428; number_of_response:1; }","duration":"302.538201ms","start":"2026-05-29T02:54:22.728584Z","end":"2026-05-29T02:54:23.031122Z","steps":["trace[906080530] 'process raft request' (duration: 302.254836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:23.031197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.369258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-29T02:54:23.031315Z","caller":"traceutil/trace.go:171","msg":"trace[1337261674] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24428; }","duration":"300.53219ms","start":"2026-05-29T02:54:22.730756Z","end":"2026-05-29T02:54:23.031288Z","steps":["trace[1337261674] 'agreement among raft nodes before linearized reading' (duration: 300.267935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:23.031254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:54:22.728574Z","time spent":"302.626093ms","remote":"127.0.0.1:36598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":718,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T02:54:23.031385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:54:22.730746Z","time spent":"300.627563ms","remote":"127.0.0.1:36796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-05-29T02:54:23.069996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.015192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-29T02:54:23.070078Z","caller":"traceutil/trace.go:171","msg":"trace[843526599] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:24429; }","duration":"230.146485ms","start":"2026-05-29T02:54:22.839912Z","end":"2026-05-29T02:54:23.070058Z","steps":["trace[843526599] 'agreement among raft nodes before linearized reading' (duration: 229.999932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:23.0701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.695698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-29T02:54:23.070177Z","caller":"traceutil/trace.go:171","msg":"trace[1553958977] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:24429; }","duration":"170.845251ms","start":"2026-05-29T02:54:22.899312Z","end":"2026-05-29T02:54:23.070158Z","steps":["trace[1553958977] 'agreement among raft nodes before linearized reading' (duration: 170.727828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:54:26.587277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.15551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-29T02:54:26.587576Z","caller":"traceutil/trace.go:171","msg":"trace[1180517142] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:24499; }","duration":"276.517057ms","start":"2026-05-29T02:54:26.311027Z","end":"2026-05-29T02:54:26.587544Z","steps":["trace[1180517142] 'count revisions from in-memory index tree' (duration: 275.67116ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:26.699284Z","caller":"traceutil/trace.go:171","msg":"trace[318512955] transaction","detail":"{read_only:false; response_revision:24500; number_of_response:1; }","duration":"110.642237ms","start":"2026-05-29T02:54:26.588613Z","end":"2026-05-29T02:54:26.699255Z","steps":["trace[318512955] 'process raft request' (duration: 110.462794ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:54:59.81652Z","caller":"traceutil/trace.go:171","msg":"trace[928811770] transaction","detail":"{read_only:false; response_revision:24790; number_of_response:1; }","duration":"140.395827ms","start":"2026-05-29T02:54:59.676097Z","end":"2026-05-29T02:54:59.816493Z","steps":["trace[928811770] 'process raft request' (duration: 140.280015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:55:00.06833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.29252ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-29T02:55:00.06843Z","caller":"traceutil/trace.go:171","msg":"trace[39215152] transaction","detail":"{read_only:false; response_revision:24791; number_of_response:1; }","duration":"359.771134ms","start":"2026-05-29T02:54:59.708641Z","end":"2026-05-29T02:55:00.068412Z","steps":["trace[39215152] 'process raft request' (duration: 250.32345ms)","trace[39215152] 'compare' (duration: 108.870782ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:55:00.068491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:54:59.708633Z","time spent":"359.832316ms","remote":"127.0.0.1:36794","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-05-29T02:55:00.235919Z","caller":"traceutil/trace.go:171","msg":"trace[860165986] transaction","detail":"{read_only:false; response_revision:24792; number_of_response:1; }","duration":"230.788038ms","start":"2026-05-29T02:55:00.005099Z","end":"2026-05-29T02:55:00.235887Z","steps":["trace[860165986] 'process raft request' (duration: 230.602514ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:00.527227Z","caller":"traceutil/trace.go:171","msg":"trace[2101458334] linearizableReadLoop","detail":"{readStateIndex:25598; appliedIndex:25596; }","duration":"451.018782ms","start":"2026-05-29T02:55:00.076177Z","end":"2026-05-29T02:55:00.527195Z","steps":["trace[2101458334] 'read index received' (duration: 159.611484ms)","trace[2101458334] 'applied index is now lower than readState.Index' (duration: 291.405818ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:55:00.527328Z","caller":"traceutil/trace.go:171","msg":"trace[1925738843] transaction","detail":"{read_only:false; response_revision:24793; number_of_response:1; }","duration":"488.944526ms","start":"2026-05-29T02:55:00.038341Z","end":"2026-05-29T02:55:00.527286Z","steps":["trace[1925738843] 'process raft request' (duration: 468.343051ms)","trace[1925738843] 'compare' (duration: 20.103826ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:55:00.527418Z","caller":"traceutil/trace.go:171","msg":"trace[45643096] transaction","detail":"{read_only:false; response_revision:24794; number_of_response:1; }","duration":"421.63207ms","start":"2026-05-29T02:55:00.105756Z","end":"2026-05-29T02:55:00.527388Z","steps":["trace[45643096] 'process raft request' (duration: 421.321254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:55:00.527485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:55:00.038314Z","time spent":"489.092929ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:55:00.527568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:55:00.105729Z","time spent":"421.745243ms","remote":"127.0.0.1:36758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5417,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T02:55:00.52778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.338903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-29T02:55:00.527832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.646894ms","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-05-29T02:55:00.527867Z","caller":"traceutil/trace.go:171","msg":"trace[1550063988] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24794; }","duration":"267.477956ms","start":"2026-05-29T02:55:00.260368Z","end":"2026-05-29T02:55:00.527846Z","steps":["trace[1550063988] 'agreement among raft nodes before linearized reading' (duration: 267.306193ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:55:00.527875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.201101ms","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-05-29T02:55:00.527879Z","caller":"traceutil/trace.go:171","msg":"trace[1295768791] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24794; }","duration":"160.714576ms","start":"2026-05-29T02:55:00.367146Z","end":"2026-05-29T02:55:00.527861Z","steps":["trace[1295768791] 'agreement among raft nodes before linearized reading' (duration: 160.570173ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:00.527913Z","caller":"traceutil/trace.go:171","msg":"trace[1927286794] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24794; }","duration":"196.274533ms","start":"2026-05-29T02:55:00.331627Z","end":"2026-05-29T02:55:00.527902Z","steps":["trace[1927286794] 'agreement among raft nodes before linearized reading' (duration: 196.108639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:55:00.527986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.813597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-29T02:55:00.52802Z","caller":"traceutil/trace.go:171","msg":"trace[407503554] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:24794; }","duration":"451.872389ms","start":"2026-05-29T02:55:00.076134Z","end":"2026-05-29T02:55:00.528006Z","steps":["trace[407503554] 'agreement among raft nodes before linearized reading' (duration: 451.821348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:55:00.528054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:55:00.076117Z","time spent":"451.929429ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-05-29T02:55:00.774732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.508395ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-05-29T02:55:00.774864Z","caller":"traceutil/trace.go:171","msg":"trace[390780704] transaction","detail":"{read_only:false; response_revision:24795; number_of_response:1; }","duration":"242.225647ms","start":"2026-05-29T02:55:00.5326Z","end":"2026-05-29T02:55:00.774825Z","steps":["trace[390780704] 'process raft request' (duration: 133.447227ms)","trace[390780704] 'compare' (duration: 108.164248ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:55:00.853636Z","caller":"traceutil/trace.go:171","msg":"trace[584051909] transaction","detail":"{read_only:false; response_revision:24796; number_of_response:1; }","duration":"320.727348ms","start":"2026-05-29T02:55:00.532878Z","end":"2026-05-29T02:55:00.853605Z","steps":["trace[584051909] 'process raft request' (duration: 320.534454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:55:00.853905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:55:00.532851Z","time spent":"320.942952ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:55:00.854002Z","caller":"traceutil/trace.go:171","msg":"trace[278426937] transaction","detail":"{read_only:false; response_revision:24797; number_of_response:1; }","duration":"320.568635ms","start":"2026-05-29T02:55:00.533398Z","end":"2026-05-29T02:55:00.853967Z","steps":["trace[278426937] 'process raft request' (duration: 320.162787ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:00.854034Z","caller":"traceutil/trace.go:171","msg":"trace[815568976] transaction","detail":"{read_only:false; response_revision:24798; number_of_response:1; }","duration":"319.506953ms","start":"2026-05-29T02:55:00.534483Z","end":"2026-05-29T02:55:00.85399Z","steps":["trace[815568976] 'process raft request' (duration: 319.280389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:55:00.854158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:55:00.533384Z","time spent":"320.687547ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:55:00.854188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:55:00.534469Z","time spent":"319.641017ms","remote":"127.0.0.1:36764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:55:00.854241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.461712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/openstack/\" range_end:\"/registry/limitranges/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-29T02:55:00.854161Z","caller":"traceutil/trace.go:171","msg":"trace[1460754913] linearizableReadLoop","detail":"{readStateIndex:25603; appliedIndex:25599; }","duration":"318.36201ms","start":"2026-05-29T02:55:00.535785Z","end":"2026-05-29T02:55:00.854147Z","steps":["trace[1460754913] 'read index received' (duration: 130.276293ms)","trace[1460754913] 'applied index is now lower than readState.Index' (duration: 188.083827ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:55:00.854277Z","caller":"traceutil/trace.go:171","msg":"trace[76794482] range","detail":"{range_begin:/registry/limitranges/openstack/; range_end:/registry/limitranges/openstack0; response_count:0; response_revision:24798; }","duration":"318.543014ms","start":"2026-05-29T02:55:00.535724Z","end":"2026-05-29T02:55:00.854267Z","steps":["trace[76794482] 'agreement among raft nodes before linearized reading' (duration: 318.468322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:55:00.854309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:55:00.535704Z","time spent":"318.597155ms","remote":"127.0.0.1:36676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/openstack/\" range_end:\"/registry/limitranges/openstack0\" "} {"level":"warn","ts":"2026-05-29T02:55:01.019369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.942848ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-29T02:55:01.019483Z","caller":"traceutil/trace.go:171","msg":"trace[590479120] transaction","detail":"{read_only:false; response_revision:24799; number_of_response:1; }","duration":"207.321134ms","start":"2026-05-29T02:55:00.812141Z","end":"2026-05-29T02:55:01.019463Z","steps":["trace[590479120] 'process raft request' (duration: 67.196022ms)","trace[590479120] 'compare' (duration: 139.824266ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:55:01.020034Z","caller":"traceutil/trace.go:171","msg":"trace[1554593214] transaction","detail":"{read_only:false; response_revision:24803; number_of_response:1; }","duration":"146.928549ms","start":"2026-05-29T02:55:00.873093Z","end":"2026-05-29T02:55:01.020021Z","steps":["trace[1554593214] 'process raft request' (duration: 146.891768ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:01.020026Z","caller":"traceutil/trace.go:171","msg":"trace[949654361] transaction","detail":"{read_only:false; response_revision:24800; number_of_response:1; }","duration":"163.028563ms","start":"2026-05-29T02:55:00.856958Z","end":"2026-05-29T02:55:01.019987Z","steps":["trace[949654361] 'process raft request' (duration: 162.86339ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:01.02014Z","caller":"traceutil/trace.go:171","msg":"trace[1814544147] transaction","detail":"{read_only:false; response_revision:24802; number_of_response:1; }","duration":"148.858117ms","start":"2026-05-29T02:55:00.871273Z","end":"2026-05-29T02:55:01.020131Z","steps":["trace[1814544147] 'process raft request' (duration: 148.675064ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:01.02014Z","caller":"traceutil/trace.go:171","msg":"trace[2092121843] transaction","detail":"{read_only:false; response_revision:24801; number_of_response:1; }","duration":"162.8879ms","start":"2026-05-29T02:55:00.857225Z","end":"2026-05-29T02:55:01.020113Z","steps":["trace[2092121843] 'process raft request' (duration: 162.677876ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:01.180533Z","caller":"traceutil/trace.go:171","msg":"trace[1195681406] transaction","detail":"{read_only:false; response_revision:24804; number_of_response:1; }","duration":"150.582652ms","start":"2026-05-29T02:55:01.02992Z","end":"2026-05-29T02:55:01.180503Z","steps":["trace[1195681406] 'process raft request' (duration: 150.338847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:55:01.471169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.416106ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-05-29T02:55:01.471361Z","caller":"traceutil/trace.go:171","msg":"trace[530894860] linearizableReadLoop","detail":"{readStateIndex:25611; appliedIndex:25608; }","duration":"421.554068ms","start":"2026-05-29T02:55:01.049789Z","end":"2026-05-29T02:55:01.471343Z","steps":["trace[530894860] 'read index received' (duration: 130.452787ms)","trace[530894860] 'applied index is now lower than readState.Index' (duration: 291.100321ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:55:01.471532Z","caller":"traceutil/trace.go:171","msg":"trace[680604733] transaction","detail":"{read_only:false; response_revision:24805; number_of_response:1; }","duration":"440.634602ms","start":"2026-05-29T02:55:01.030885Z","end":"2026-05-29T02:55:01.47152Z","steps":["trace[680604733] 'process raft request' (duration: 311.803688ms)","trace[680604733] 'compare' (duration: 128.1399ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:55:01.471597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:55:01.030872Z","time spent":"440.700064ms","remote":"127.0.0.1:36598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":720,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T02:55:01.471914Z","caller":"traceutil/trace.go:171","msg":"trace[1981254956] transaction","detail":"{read_only:false; response_revision:24806; number_of_response:1; }","duration":"441.018499ms","start":"2026-05-29T02:55:01.030885Z","end":"2026-05-29T02:55:01.471904Z","steps":["trace[1981254956] 'process raft request' (duration: 440.379927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:55:01.47198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:55:01.030866Z","time spent":"441.078431ms","remote":"127.0.0.1:36758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5599,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:55:01.472207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.421975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7914"} {"level":"info","ts":"2026-05-29T02:55:01.472239Z","caller":"traceutil/trace.go:171","msg":"trace[940348877] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:24806; }","duration":"422.482817ms","start":"2026-05-29T02:55:01.049748Z","end":"2026-05-29T02:55:01.472231Z","steps":["trace[940348877] 'agreement among raft nodes before linearized reading' (duration: 422.334094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:55:01.472264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:55:01.049737Z","time spent":"422.521898ms","remote":"127.0.0.1:58710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7936,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "} {"level":"info","ts":"2026-05-29T02:55:01.614734Z","caller":"traceutil/trace.go:171","msg":"trace[1490395388] transaction","detail":"{read_only:false; response_revision:24807; number_of_response:1; }","duration":"428.214032ms","start":"2026-05-29T02:55:01.186493Z","end":"2026-05-29T02:55:01.614707Z","steps":["trace[1490395388] 'process raft request' (duration: 421.350644ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:01.614762Z","caller":"traceutil/trace.go:171","msg":"trace[1286860384] linearizableReadLoop","detail":"{readStateIndex:25613; appliedIndex:25611; }","duration":"143.329396ms","start":"2026-05-29T02:55:01.471397Z","end":"2026-05-29T02:55:01.614726Z","steps":["trace[1286860384] 'read index received' (duration: 136.325855ms)","trace[1286860384] 'applied index is now lower than readState.Index' (duration: 7.001791ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:55:01.614853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:55:01.186469Z","time spent":"428.325145ms","remote":"127.0.0.1:36598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":753,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T02:55:01.614845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.490174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-29T02:55:01.614936Z","caller":"traceutil/trace.go:171","msg":"trace[1891531674] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24808; }","duration":"330.618857ms","start":"2026-05-29T02:55:01.284298Z","end":"2026-05-29T02:55:01.614917Z","steps":["trace[1891531674] 'agreement among raft nodes before linearized reading' (duration: 330.434793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:55:01.614983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:55:01.284281Z","time spent":"330.694928ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T02:55:01.61499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.625295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"warn","ts":"2026-05-29T02:55:01.615069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.302932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/heat-engine-cleaner-29667055-d4p4q\" ","response":"range_response_count:1 size:5678"} {"level":"info","ts":"2026-05-29T02:55:01.615091Z","caller":"traceutil/trace.go:171","msg":"trace[1651967807] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24808; }","duration":"168.796368ms","start":"2026-05-29T02:55:01.44627Z","end":"2026-05-29T02:55:01.615066Z","steps":["trace[1651967807] 'agreement among raft nodes before linearized reading' (duration: 168.486132ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:01.615121Z","caller":"traceutil/trace.go:171","msg":"trace[443769922] range","detail":"{range_begin:/registry/pods/openstack/heat-engine-cleaner-29667055-d4p4q; range_end:; response_count:1; response_revision:24808; }","duration":"414.449405ms","start":"2026-05-29T02:55:01.200655Z","end":"2026-05-29T02:55:01.615105Z","steps":["trace[443769922] 'agreement among raft nodes before linearized reading' (duration: 414.19708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:55:01.615159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.998626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-29T02:55:01.615174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:55:01.200633Z","time spent":"414.528997ms","remote":"127.0.0.1:36728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":5700,"request content":"key:\"/registry/pods/openstack/heat-engine-cleaner-29667055-d4p4q\" "} {"level":"info","ts":"2026-05-29T02:55:01.615201Z","caller":"traceutil/trace.go:171","msg":"trace[1456569781] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:24808; }","duration":"409.067766ms","start":"2026-05-29T02:55:01.206122Z","end":"2026-05-29T02:55:01.61519Z","steps":["trace[1456569781] 'agreement among raft nodes before linearized reading' (duration: 408.984095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:55:01.615232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:55:01.20611Z","time spent":"409.115427ms","remote":"127.0.0.1:60030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-29T02:55:01.615287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.794745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/heat-engine-cleaner\" ","response":"range_response_count:1 size:541"} {"level":"info","ts":"2026-05-29T02:55:01.615376Z","caller":"traceutil/trace.go:171","msg":"trace[430462800] range","detail":"{range_begin:/registry/serviceaccounts/openstack/heat-engine-cleaner; range_end:; response_count:1; response_revision:24808; }","duration":"216.933808ms","start":"2026-05-29T02:55:01.398421Z","end":"2026-05-29T02:55:01.615355Z","steps":["trace[430462800] 'agreement among raft nodes before linearized reading' (duration: 216.707123ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:01.615377Z","caller":"traceutil/trace.go:171","msg":"trace[1536534418] transaction","detail":"{read_only:false; response_revision:24808; number_of_response:1; }","duration":"405.213788ms","start":"2026-05-29T02:55:01.210146Z","end":"2026-05-29T02:55:01.61536Z","steps":["trace[1536534418] 'process raft request' (duration: 404.450523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:55:01.615499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:55:01.210131Z","time spent":"405.29089ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:55:01.61556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.775675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-29T02:55:01.615606Z","caller":"traceutil/trace.go:171","msg":"trace[18177337] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24808; }","duration":"360.841645ms","start":"2026-05-29T02:55:01.254745Z","end":"2026-05-29T02:55:01.615587Z","steps":["trace[18177337] 'agreement among raft nodes before linearized reading' (duration: 360.701603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:55:01.615647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:55:01.254733Z","time spent":"360.906127ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-29T02:55:01.616187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.58148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-29T02:55:01.616479Z","caller":"traceutil/trace.go:171","msg":"trace[108513003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24808; }","duration":"366.771635ms","start":"2026-05-29T02:55:01.249504Z","end":"2026-05-29T02:55:01.616276Z","steps":["trace[108513003] 'agreement among raft nodes before linearized reading' (duration: 365.287815ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:55:01.616661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:55:01.249487Z","time spent":"367.092201ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T02:55:01.615012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.442108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-29T02:55:01.61822Z","caller":"traceutil/trace.go:171","msg":"trace[1977070476] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24808; }","duration":"368.700663ms","start":"2026-05-29T02:55:01.249501Z","end":"2026-05-29T02:55:01.618202Z","steps":["trace[1977070476] 'agreement among raft nodes before linearized reading' (duration: 365.318795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:55:01.618264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:55:01.249477Z","time spent":"368.776385ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T02:55:02.026606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.46321ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-29T02:55:02.026843Z","caller":"traceutil/trace.go:171","msg":"trace[1339358694] linearizableReadLoop","detail":"{readStateIndex:25617; appliedIndex:25613; }","duration":"206.382976ms","start":"2026-05-29T02:55:01.820441Z","end":"2026-05-29T02:55:02.026824Z","steps":["trace[1339358694] 'read index received' (duration: 54.718492ms)","trace[1339358694] 'applied index is now lower than readState.Index' (duration: 151.663034ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:55:02.026861Z","caller":"traceutil/trace.go:171","msg":"trace[1756029011] transaction","detail":"{read_only:false; response_revision:24809; number_of_response:1; }","duration":"404.181209ms","start":"2026-05-29T02:55:01.622616Z","end":"2026-05-29T02:55:02.026797Z","steps":["trace[1756029011] 'process raft request' (duration: 252.438363ms)","trace[1756029011] 'compare' (duration: 151.346108ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:55:02.02694Z","caller":"traceutil/trace.go:171","msg":"trace[1173465959] transaction","detail":"{read_only:false; response_revision:24811; number_of_response:1; }","duration":"404.095296ms","start":"2026-05-29T02:55:01.622838Z","end":"2026-05-29T02:55:02.026934Z","steps":["trace[1173465959] 'process raft request' (duration: 403.918233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:55:02.026998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:55:01.622829Z","time spent":"404.140797ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T02:55:02.027028Z","caller":"traceutil/trace.go:171","msg":"trace[1371294365] transaction","detail":"{read_only:false; response_revision:24812; number_of_response:1; }","duration":"402.964674ms","start":"2026-05-29T02:55:01.624041Z","end":"2026-05-29T02:55:02.027006Z","steps":["trace[1371294365] 'process raft request' (duration: 402.743049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:55:02.027101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:55:01.622597Z","time spent":"404.378122ms","remote":"127.0.0.1:36728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6970,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:55:02.027137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:55:01.624033Z","time spent":"403.045406ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T02:55:02.027274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.830745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-29T02:55:02.027303Z","caller":"traceutil/trace.go:171","msg":"trace[1636457671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24812; }","duration":"206.916346ms","start":"2026-05-29T02:55:01.820377Z","end":"2026-05-29T02:55:02.027293Z","steps":["trace[1636457671] 'agreement among raft nodes before linearized reading' (duration: 206.783174ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:02.026918Z","caller":"traceutil/trace.go:171","msg":"trace[1454678257] transaction","detail":"{read_only:false; response_revision:24810; number_of_response:1; }","duration":"404.125487ms","start":"2026-05-29T02:55:01.622781Z","end":"2026-05-29T02:55:02.026907Z","steps":["trace[1454678257] 'process raft request' (duration: 403.920113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:55:02.027401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:55:01.622765Z","time spent":"404.609667ms","remote":"127.0.0.1:36794","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-05-29T02:55:02.328883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.221402ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-29T02:55:02.329185Z","caller":"traceutil/trace.go:171","msg":"trace[1678928063] linearizableReadLoop","detail":"{readStateIndex:25618; appliedIndex:25617; }","duration":"302.294487ms","start":"2026-05-29T02:55:02.026868Z","end":"2026-05-29T02:55:02.329163Z","steps":["trace[1678928063] 'read index received' (duration: 28.721808ms)","trace[1678928063] 'applied index is now lower than readState.Index' (duration: 273.571309ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:55:02.329712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.96679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-05-29T02:55:02.329798Z","caller":"traceutil/trace.go:171","msg":"trace[930546427] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24812; }","duration":"376.086693ms","start":"2026-05-29T02:55:01.953687Z","end":"2026-05-29T02:55:02.329773Z","steps":["trace[930546427] 'agreement among raft nodes before linearized reading' (duration: 375.847588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:55:02.329849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:55:01.953671Z","time spent":"376.168325ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":584,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-05-29T02:55:02.329727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.035522ms","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-05-29T02:55:02.330124Z","caller":"traceutil/trace.go:171","msg":"trace[2004180139] transaction","detail":"{read_only:false; response_revision:24813; number_of_response:1; }","duration":"299.03536ms","start":"2026-05-29T02:55:02.03105Z","end":"2026-05-29T02:55:02.330085Z","steps":["trace[2004180139] 'process raft request' (duration: 297.977549ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:02.330073Z","caller":"traceutil/trace.go:171","msg":"trace[107648332] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24812; }","duration":"382.397349ms","start":"2026-05-29T02:55:01.947616Z","end":"2026-05-29T02:55:02.330014Z","steps":["trace[107648332] 'agreement among raft nodes before linearized reading' (duration: 382.024822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:55:02.330395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T02:55:01.947581Z","time spent":"382.755966ms","remote":"127.0.0.1:40416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-29T02:55:02.398873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.133771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-29T02:55:02.39894Z","caller":"traceutil/trace.go:171","msg":"trace[394238491] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24814; }","duration":"140.231254ms","start":"2026-05-29T02:55:02.258689Z","end":"2026-05-29T02:55:02.39892Z","steps":["trace[394238491] 'agreement among raft nodes before linearized reading' (duration: 140.106951ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:02.398937Z","caller":"traceutil/trace.go:171","msg":"trace[1794482433] transaction","detail":"{read_only:false; response_revision:24814; number_of_response:1; }","duration":"157.570622ms","start":"2026-05-29T02:55:02.241318Z","end":"2026-05-29T02:55:02.398888Z","steps":["trace[1794482433] 'process raft request' (duration: 89.324358ms)","trace[1794482433] 'compare' (duration: 67.915457ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:55:02.399092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.635438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-29T02:55:02.399111Z","caller":"traceutil/trace.go:171","msg":"trace[659052499] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:24814; }","duration":"114.689789ms","start":"2026-05-29T02:55:02.284414Z","end":"2026-05-29T02:55:02.399104Z","steps":["trace[659052499] 'agreement among raft nodes before linearized reading' (duration: 114.661399ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:02.531513Z","caller":"traceutil/trace.go:171","msg":"trace[1282301445] transaction","detail":"{read_only:false; response_revision:24815; number_of_response:1; }","duration":"197.463066ms","start":"2026-05-29T02:55:02.334023Z","end":"2026-05-29T02:55:02.531486Z","steps":["trace[1282301445] 'process raft request' (duration: 177.485514ms)","trace[1282301445] 'compare' (duration: 19.415101ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:55:02.760006Z","caller":"traceutil/trace.go:171","msg":"trace[403871035] transaction","detail":"{read_only:false; response_revision:24818; number_of_response:1; }","duration":"216.259624ms","start":"2026-05-29T02:55:02.543717Z","end":"2026-05-29T02:55:02.759976Z","steps":["trace[403871035] 'process raft request' (duration: 216.03839ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:02.938826Z","caller":"traceutil/trace.go:171","msg":"trace[1208405095] linearizableReadLoop","detail":"{readStateIndex:25625; appliedIndex:25623; }","duration":"214.237644ms","start":"2026-05-29T02:55:02.724553Z","end":"2026-05-29T02:55:02.938791Z","steps":["trace[1208405095] 'read index received' (duration: 35.261721ms)","trace[1208405095] 'applied index is now lower than readState.Index' (duration: 178.974363ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:55:02.939049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.480749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-05-29T02:55:02.939113Z","caller":"traceutil/trace.go:171","msg":"trace[1490518729] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24819; }","duration":"214.592061ms","start":"2026-05-29T02:55:02.724506Z","end":"2026-05-29T02:55:02.939098Z","steps":["trace[1490518729] 'agreement among raft nodes before linearized reading' (duration: 214.429327ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:02.939217Z","caller":"traceutil/trace.go:171","msg":"trace[1383061768] transaction","detail":"{read_only:false; response_revision:24819; number_of_response:1; }","duration":"219.347677ms","start":"2026-05-29T02:55:02.719862Z","end":"2026-05-29T02:55:02.939209Z","steps":["trace[1383061768] 'process raft request' (duration: 218.138572ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:03.166388Z","caller":"traceutil/trace.go:171","msg":"trace[1051538804] transaction","detail":"{read_only:false; response_revision:24821; number_of_response:1; }","duration":"222.269806ms","start":"2026-05-29T02:55:02.944096Z","end":"2026-05-29T02:55:03.166366Z","steps":["trace[1051538804] 'process raft request' (duration: 221.835667ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:03.166528Z","caller":"traceutil/trace.go:171","msg":"trace[1779404855] linearizableReadLoop","detail":"{readStateIndex:25628; appliedIndex:25625; }","duration":"219.356367ms","start":"2026-05-29T02:55:02.947148Z","end":"2026-05-29T02:55:03.166504Z","steps":["trace[1779404855] 'read index received' (duration: 24.87915ms)","trace[1779404855] 'applied index is now lower than readState.Index' (duration: 194.475937ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:55:03.166708Z","caller":"traceutil/trace.go:171","msg":"trace[1760216291] transaction","detail":"{read_only:false; response_revision:24822; number_of_response:1; }","duration":"222.566741ms","start":"2026-05-29T02:55:02.944117Z","end":"2026-05-29T02:55:03.166684Z","steps":["trace[1760216291] 'process raft request' (duration: 222.173423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:55:03.166836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.676063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18571"} {"level":"info","ts":"2026-05-29T02:55:03.166904Z","caller":"traceutil/trace.go:171","msg":"trace[944464127] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:24822; }","duration":"219.778315ms","start":"2026-05-29T02:55:02.947112Z","end":"2026-05-29T02:55:03.166891Z","steps":["trace[944464127] 'agreement among raft nodes before linearized reading' (duration: 219.558751ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:03.196511Z","caller":"traceutil/trace.go:171","msg":"trace[1619359330] transaction","detail":"{read_only:false; response_revision:24823; number_of_response:1; }","duration":"166.82139ms","start":"2026-05-29T02:55:03.029675Z","end":"2026-05-29T02:55:03.196496Z","steps":["trace[1619359330] 'process raft request' (duration: 166.682647ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:03.196658Z","caller":"traceutil/trace.go:171","msg":"trace[1536007727] transaction","detail":"{read_only:false; response_revision:24824; number_of_response:1; }","duration":"166.670216ms","start":"2026-05-29T02:55:03.029982Z","end":"2026-05-29T02:55:03.196652Z","steps":["trace[1536007727] 'process raft request' (duration: 166.473742ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:03.196875Z","caller":"traceutil/trace.go:171","msg":"trace[1598496652] transaction","detail":"{read_only:false; response_revision:24825; number_of_response:1; }","duration":"166.767068ms","start":"2026-05-29T02:55:03.030089Z","end":"2026-05-29T02:55:03.196856Z","steps":["trace[1598496652] 'process raft request' (duration: 166.537723ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:03.197101Z","caller":"traceutil/trace.go:171","msg":"trace[1232811199] transaction","detail":"{read_only:false; response_revision:24826; number_of_response:1; }","duration":"166.718857ms","start":"2026-05-29T02:55:03.030369Z","end":"2026-05-29T02:55:03.197088Z","steps":["trace[1232811199] 'process raft request' (duration: 166.428152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:55:03.197313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.043564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-29T02:55:03.197363Z","caller":"traceutil/trace.go:171","msg":"trace[2085901932] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:24826; }","duration":"176.114187ms","start":"2026-05-29T02:55:03.021238Z","end":"2026-05-29T02:55:03.197352Z","steps":["trace[2085901932] 'agreement among raft nodes before linearized reading' (duration: 175.850751ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:03.771915Z","caller":"traceutil/trace.go:171","msg":"trace[710389857] transaction","detail":"{read_only:false; response_revision:24827; number_of_response:1; }","duration":"151.46726ms","start":"2026-05-29T02:55:03.620415Z","end":"2026-05-29T02:55:03.771882Z","steps":["trace[710389857] 'process raft request' (duration: 151.169044ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:04.178662Z","caller":"traceutil/trace.go:171","msg":"trace[1143128289] transaction","detail":"{read_only:false; response_revision:24831; number_of_response:1; }","duration":"141.737953ms","start":"2026-05-29T02:55:04.036896Z","end":"2026-05-29T02:55:04.178634Z","steps":["trace[1143128289] 'process raft request' (duration: 141.411587ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:04.178696Z","caller":"traceutil/trace.go:171","msg":"trace[664169851] transaction","detail":"{read_only:false; response_revision:24830; number_of_response:1; }","duration":"143.355966ms","start":"2026-05-29T02:55:04.035292Z","end":"2026-05-29T02:55:04.178648Z","steps":["trace[664169851] 'process raft request' (duration: 91.167966ms)","trace[664169851] 'compare' (duration: 51.65367ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:55:04.1787Z","caller":"traceutil/trace.go:171","msg":"trace[332160312] linearizableReadLoop","detail":"{readStateIndex:25637; appliedIndex:25635; }","duration":"102.660697ms","start":"2026-05-29T02:55:04.076008Z","end":"2026-05-29T02:55:04.178668Z","steps":["trace[332160312] 'read index received' (duration: 13.44316ms)","trace[332160312] 'applied index is now lower than readState.Index' (duration: 89.214967ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:55:04.179253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.231618ms","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-05-29T02:55:04.179347Z","caller":"traceutil/trace.go:171","msg":"trace[520327979] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24832; }","duration":"103.34156ms","start":"2026-05-29T02:55:04.075992Z","end":"2026-05-29T02:55:04.179334Z","steps":["trace[520327979] 'agreement among raft nodes before linearized reading' (duration: 103.061604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:55:04.48503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.942279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-29T02:55:04.485142Z","caller":"traceutil/trace.go:171","msg":"trace[2004518650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24832; }","duration":"151.124983ms","start":"2026-05-29T02:55:04.333991Z","end":"2026-05-29T02:55:04.485116Z","steps":["trace[2004518650] 'range keys from in-memory index tree' (duration: 150.665823ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:05.01245Z","caller":"traceutil/trace.go:171","msg":"trace[1811149523] transaction","detail":"{read_only:false; response_revision:24836; number_of_response:1; }","duration":"122.881494ms","start":"2026-05-29T02:55:04.889536Z","end":"2026-05-29T02:55:05.012417Z","steps":["trace[1811149523] 'process raft request' (duration: 70.259114ms)","trace[1811149523] 'compare' (duration: 52.399426ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:55:05.270367Z","caller":"traceutil/trace.go:171","msg":"trace[1724852070] transaction","detail":"{read_only:false; response_revision:24837; number_of_response:1; }","duration":"207.974837ms","start":"2026-05-29T02:55:05.062357Z","end":"2026-05-29T02:55:05.270332Z","steps":["trace[1724852070] 'process raft request' (duration: 207.708972ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:05.354426Z","caller":"traceutil/trace.go:171","msg":"trace[730278675] transaction","detail":"{read_only:false; response_revision:24839; number_of_response:1; }","duration":"152.42638ms","start":"2026-05-29T02:55:05.201957Z","end":"2026-05-29T02:55:05.354383Z","steps":["trace[730278675] 'process raft request' (duration: 152.175375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:55:05.354528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.601216ms","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-05-29T02:55:05.3546Z","caller":"traceutil/trace.go:171","msg":"trace[92993756] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24841; }","duration":"183.545777ms","start":"2026-05-29T02:55:05.171037Z","end":"2026-05-29T02:55:05.354583Z","steps":["trace[92993756] 'agreement among raft nodes before linearized reading' (duration: 183.298112ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:05.354728Z","caller":"traceutil/trace.go:171","msg":"trace[391839063] transaction","detail":"{read_only:false; response_revision:24840; number_of_response:1; }","duration":"152.160553ms","start":"2026-05-29T02:55:05.202558Z","end":"2026-05-29T02:55:05.354719Z","steps":["trace[391839063] 'process raft request' (duration: 151.680534ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:05.354845Z","caller":"traceutil/trace.go:171","msg":"trace[885957250] transaction","detail":"{read_only:false; response_revision:24838; number_of_response:1; }","duration":"152.96564ms","start":"2026-05-29T02:55:05.201855Z","end":"2026-05-29T02:55:05.354821Z","steps":["trace[885957250] 'process raft request' (duration: 144.453479ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:05.354858Z","caller":"traceutil/trace.go:171","msg":"trace[1360139281] transaction","detail":"{read_only:false; response_revision:24841; number_of_response:1; }","duration":"151.48616ms","start":"2026-05-29T02:55:05.203333Z","end":"2026-05-29T02:55:05.354819Z","steps":["trace[1360139281] 'process raft request' (duration: 150.95601ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:05.426269Z","caller":"traceutil/trace.go:171","msg":"trace[773011886] transaction","detail":"{read_only:false; response_revision:24842; number_of_response:1; }","duration":"151.497021ms","start":"2026-05-29T02:55:05.274749Z","end":"2026-05-29T02:55:05.426246Z","steps":["trace[773011886] 'process raft request' (duration: 151.344087ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:06.060127Z","caller":"traceutil/trace.go:171","msg":"trace[1952228596] linearizableReadLoop","detail":"{readStateIndex:25652; appliedIndex:25651; }","duration":"183.541096ms","start":"2026-05-29T02:55:05.876552Z","end":"2026-05-29T02:55:06.060093Z","steps":["trace[1952228596] 'read index received' (duration: 138.015859ms)","trace[1952228596] 'applied index is now lower than readState.Index' (duration: 45.523897ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:55:06.060181Z","caller":"traceutil/trace.go:171","msg":"trace[2061624657] transaction","detail":"{read_only:false; response_revision:24846; number_of_response:1; }","duration":"215.232614ms","start":"2026-05-29T02:55:05.844916Z","end":"2026-05-29T02:55:06.060148Z","steps":["trace[2061624657] 'process raft request' (duration: 169.732928ms)","trace[2061624657] 'compare' (duration: 45.125329ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:55:06.060366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.514894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-29T02:55:06.060463Z","caller":"traceutil/trace.go:171","msg":"trace[1824527571] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:24846; }","duration":"252.656688ms","start":"2026-05-29T02:55:05.807781Z","end":"2026-05-29T02:55:06.060438Z","steps":["trace[1824527571] 'agreement among raft nodes before linearized reading' (duration: 252.470564ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:06.280429Z","caller":"traceutil/trace.go:171","msg":"trace[1876267297] linearizableReadLoop","detail":"{readStateIndex:25653; appliedIndex:25652; }","duration":"185.416643ms","start":"2026-05-29T02:55:06.094982Z","end":"2026-05-29T02:55:06.280398Z","steps":["trace[1876267297] 'read index received' (duration: 169.31203ms)","trace[1876267297] 'applied index is now lower than readState.Index' (duration: 16.087093ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:55:06.280493Z","caller":"traceutil/trace.go:171","msg":"trace[583399170] transaction","detail":"{read_only:false; response_revision:24847; number_of_response:1; }","duration":"209.887615ms","start":"2026-05-29T02:55:06.070557Z","end":"2026-05-29T02:55:06.280444Z","steps":["trace[583399170] 'process raft request' (duration: 193.733491ms)","trace[583399170] 'compare' (duration: 15.95817ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:55:06.280756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.758732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-29T02:55:06.280829Z","caller":"traceutil/trace.go:171","msg":"trace[1443745285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24847; }","duration":"185.890484ms","start":"2026-05-29T02:55:06.094922Z","end":"2026-05-29T02:55:06.280813Z","steps":["trace[1443745285] 'agreement among raft nodes before linearized reading' (duration: 185.660029ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:06.525659Z","caller":"traceutil/trace.go:171","msg":"trace[1622108896] transaction","detail":"{read_only:false; response_revision:24849; number_of_response:1; }","duration":"239.129275ms","start":"2026-05-29T02:55:06.28651Z","end":"2026-05-29T02:55:06.525639Z","steps":["trace[1622108896] 'process raft request' (duration: 233.035052ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:06.525776Z","caller":"traceutil/trace.go:171","msg":"trace[34310541] linearizableReadLoop","detail":"{readStateIndex:25656; appliedIndex:25653; }","duration":"166.253717ms","start":"2026-05-29T02:55:06.3595Z","end":"2026-05-29T02:55:06.525754Z","steps":["trace[34310541] 'read index received' (duration: 3.238005ms)","trace[34310541] 'applied index is now lower than readState.Index' (duration: 163.014612ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:55:06.525969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.449671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-29T02:55:06.525987Z","caller":"traceutil/trace.go:171","msg":"trace[1546366568] transaction","detail":"{read_only:false; response_revision:24850; number_of_response:1; }","duration":"238.484883ms","start":"2026-05-29T02:55:06.287492Z","end":"2026-05-29T02:55:06.525977Z","steps":["trace[1546366568] 'process raft request' (duration: 238.115785ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:06.526002Z","caller":"traceutil/trace.go:171","msg":"trace[2036049012] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:24850; }","duration":"166.538183ms","start":"2026-05-29T02:55:06.359452Z","end":"2026-05-29T02:55:06.52599Z","steps":["trace[2036049012] 'agreement among raft nodes before linearized reading' (duration: 166.470682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:55:06.531253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.439098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-29T02:55:06.531296Z","caller":"traceutil/trace.go:171","msg":"trace[892921447] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:24851; }","duration":"140.52613ms","start":"2026-05-29T02:55:06.390759Z","end":"2026-05-29T02:55:06.531285Z","steps":["trace[892921447] 'agreement among raft nodes before linearized reading' (duration: 140.412087ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:06.849978Z","caller":"traceutil/trace.go:171","msg":"trace[1891491712] transaction","detail":"{read_only:false; response_revision:24854; number_of_response:1; }","duration":"112.427914ms","start":"2026-05-29T02:55:06.737523Z","end":"2026-05-29T02:55:06.849951Z","steps":["trace[1891491712] 'process raft request' (duration: 112.055787ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:07.264824Z","caller":"traceutil/trace.go:171","msg":"trace[1673965933] transaction","detail":"{read_only:false; response_revision:24855; number_of_response:1; }","duration":"121.009557ms","start":"2026-05-29T02:55:07.143768Z","end":"2026-05-29T02:55:07.264778Z","steps":["trace[1673965933] 'process raft request' (duration: 40.583917ms)","trace[1673965933] 'compare' (duration: 80.253647ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:55:07.483204Z","caller":"traceutil/trace.go:171","msg":"trace[632451560] transaction","detail":"{read_only:false; response_revision:24858; number_of_response:1; }","duration":"121.196109ms","start":"2026-05-29T02:55:07.361979Z","end":"2026-05-29T02:55:07.483176Z","steps":["trace[632451560] 'process raft request' (duration: 121.088047ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:07.483224Z","caller":"traceutil/trace.go:171","msg":"trace[306159513] transaction","detail":"{read_only:false; response_revision:24859; number_of_response:1; }","duration":"121.019736ms","start":"2026-05-29T02:55:07.362183Z","end":"2026-05-29T02:55:07.483203Z","steps":["trace[306159513] 'process raft request' (duration: 120.929224ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:07.483299Z","caller":"traceutil/trace.go:171","msg":"trace[897143970] transaction","detail":"{read_only:false; response_revision:24857; number_of_response:1; }","duration":"122.17811ms","start":"2026-05-29T02:55:07.361089Z","end":"2026-05-29T02:55:07.483267Z","steps":["trace[897143970] 'process raft request' (duration: 121.867334ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:07.681155Z","caller":"traceutil/trace.go:171","msg":"trace[1490432728] transaction","detail":"{read_only:false; response_revision:24860; number_of_response:1; }","duration":"191.175799ms","start":"2026-05-29T02:55:07.489935Z","end":"2026-05-29T02:55:07.68111Z","steps":["trace[1490432728] 'process raft request' (duration: 153.305877ms)","trace[1490432728] 'compare' (duration: 37.636598ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:55:07.747847Z","caller":"traceutil/trace.go:171","msg":"trace[263125756] transaction","detail":"{read_only:false; response_revision:24861; number_of_response:1; }","duration":"257.7417ms","start":"2026-05-29T02:55:07.49006Z","end":"2026-05-29T02:55:07.747802Z","steps":["trace[263125756] 'process raft request' (duration: 257.432753ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:11.882285Z","caller":"traceutil/trace.go:171","msg":"trace[1824311348] linearizableReadLoop","detail":"{readStateIndex:25701; appliedIndex:25700; }","duration":"120.186251ms","start":"2026-05-29T02:55:11.762075Z","end":"2026-05-29T02:55:11.882261Z","steps":["trace[1824311348] 'read index received' (duration: 120.024117ms)","trace[1824311348] 'applied index is now lower than readState.Index' (duration: 161.164µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T02:55:11.882471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.382224ms","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-05-29T02:55:11.882511Z","caller":"traceutil/trace.go:171","msg":"trace[668064240] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24894; }","duration":"120.464595ms","start":"2026-05-29T02:55:11.762034Z","end":"2026-05-29T02:55:11.882499Z","steps":["trace[668064240] 'agreement among raft nodes before linearized reading' (duration: 120.319253ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:11.882513Z","caller":"traceutil/trace.go:171","msg":"trace[509001623] transaction","detail":"{read_only:false; response_revision:24894; number_of_response:1; }","duration":"186.373482ms","start":"2026-05-29T02:55:11.696111Z","end":"2026-05-29T02:55:11.882484Z","steps":["trace[509001623] 'process raft request' (duration: 186.002335ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:14.53752Z","caller":"traceutil/trace.go:171","msg":"trace[1275825819] linearizableReadLoop","detail":"{readStateIndex:25722; appliedIndex:25721; }","duration":"135.992289ms","start":"2026-05-29T02:55:14.401503Z","end":"2026-05-29T02:55:14.537495Z","steps":["trace[1275825819] 'read index received' (duration: 135.755694ms)","trace[1275825819] 'applied index is now lower than readState.Index' (duration: 235.585µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:55:14.537709Z","caller":"traceutil/trace.go:171","msg":"trace[338190367] transaction","detail":"{read_only:false; response_revision:24914; number_of_response:1; }","duration":"140.275414ms","start":"2026-05-29T02:55:14.397399Z","end":"2026-05-29T02:55:14.537675Z","steps":["trace[338190367] 'process raft request' (duration: 139.930467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:55:14.537747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.220813ms","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-05-29T02:55:14.537782Z","caller":"traceutil/trace.go:171","msg":"trace[1734638752] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24914; }","duration":"136.324345ms","start":"2026-05-29T02:55:14.401447Z","end":"2026-05-29T02:55:14.537771Z","steps":["trace[1734638752] 'agreement among raft nodes before linearized reading' (duration: 136.148692ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:14.711359Z","caller":"traceutil/trace.go:171","msg":"trace[1276773921] linearizableReadLoop","detail":"{readStateIndex:25723; appliedIndex:25722; }","duration":"152.370688ms","start":"2026-05-29T02:55:14.558961Z","end":"2026-05-29T02:55:14.711331Z","steps":["trace[1276773921] 'read index received' (duration: 152.134473ms)","trace[1276773921] 'applied index is now lower than readState.Index' (duration: 235.015µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:55:14.711413Z","caller":"traceutil/trace.go:171","msg":"trace[2035470102] transaction","detail":"{read_only:false; response_revision:24915; number_of_response:1; }","duration":"152.92829ms","start":"2026-05-29T02:55:14.558449Z","end":"2026-05-29T02:55:14.711377Z","steps":["trace[2035470102] 'process raft request' (duration: 152.713755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:55:14.711601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.090015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-29T02:55:14.711603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.638354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-29T02:55:14.711646Z","caller":"traceutil/trace.go:171","msg":"trace[560956135] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24915; }","duration":"105.170418ms","start":"2026-05-29T02:55:14.606463Z","end":"2026-05-29T02:55:14.711633Z","steps":["trace[560956135] 'agreement among raft nodes before linearized reading' (duration: 105.077626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:55:14.711643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.941679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-29T02:55:14.711667Z","caller":"traceutil/trace.go:171","msg":"trace[1265756077] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24915; }","duration":"152.752155ms","start":"2026-05-29T02:55:14.558898Z","end":"2026-05-29T02:55:14.71165Z","steps":["trace[1265756077] 'agreement among raft nodes before linearized reading' (duration: 152.533561ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:14.711709Z","caller":"traceutil/trace.go:171","msg":"trace[1693863147] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24915; }","duration":"152.032391ms","start":"2026-05-29T02:55:14.559658Z","end":"2026-05-29T02:55:14.71169Z","steps":["trace[1693863147] 'agreement among raft nodes before linearized reading' (duration: 151.844777ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:55:14.872452Z","caller":"traceutil/trace.go:171","msg":"trace[2103637833] transaction","detail":"{read_only:false; response_revision:24917; number_of_response:1; }","duration":"154.841459ms","start":"2026-05-29T02:55:14.717577Z","end":"2026-05-29T02:55:14.872419Z","steps":["trace[2103637833] 'process raft request' (duration: 128.980688ms)","trace[2103637833] 'compare' (duration: 25.392651ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:55:14.872628Z","caller":"traceutil/trace.go:171","msg":"trace[1955674769] linearizableReadLoop","detail":"{readStateIndex:25726; appliedIndex:25724; }","duration":"154.047042ms","start":"2026-05-29T02:55:14.718562Z","end":"2026-05-29T02:55:14.872609Z","steps":["trace[1955674769] 'read index received' (duration: 57.483877ms)","trace[1955674769] 'applied index is now lower than readState.Index' (duration: 96.562465ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:55:14.872641Z","caller":"traceutil/trace.go:171","msg":"trace[2068183372] transaction","detail":"{read_only:false; response_revision:24918; number_of_response:1; }","duration":"155.001032ms","start":"2026-05-29T02:55:14.71761Z","end":"2026-05-29T02:55:14.872611Z","steps":["trace[2068183372] 'process raft request' (duration: 154.721116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:55:14.87303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.45175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18571"} {"level":"info","ts":"2026-05-29T02:55:14.873076Z","caller":"traceutil/trace.go:171","msg":"trace[1646194270] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24919; }","duration":"154.539802ms","start":"2026-05-29T02:55:14.718527Z","end":"2026-05-29T02:55:14.873067Z","steps":["trace[1646194270] 'agreement among raft nodes before linearized reading' (duration: 154.186105ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:56:19.916665Z","caller":"traceutil/trace.go:171","msg":"trace[1288852557] linearizableReadLoop","detail":"{readStateIndex:26412; appliedIndex:26408; }","duration":"139.871716ms","start":"2026-05-29T02:56:19.776776Z","end":"2026-05-29T02:56:19.916648Z","steps":["trace[1288852557] 'read index received' (duration: 5.033231ms)","trace[1288852557] 'applied index is now lower than readState.Index' (duration: 134.837685ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:56:19.91673Z","caller":"traceutil/trace.go:171","msg":"trace[2022331653] transaction","detail":"{read_only:false; response_revision:25587; number_of_response:1; }","duration":"176.160817ms","start":"2026-05-29T02:56:19.740542Z","end":"2026-05-29T02:56:19.916703Z","steps":["trace[2022331653] 'process raft request' (duration: 175.939673ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:56:19.916782Z","caller":"traceutil/trace.go:171","msg":"trace[943229564] transaction","detail":"{read_only:false; response_revision:25589; number_of_response:1; }","duration":"152.03359ms","start":"2026-05-29T02:56:19.764743Z","end":"2026-05-29T02:56:19.916777Z","steps":["trace[943229564] 'process raft request' (duration: 151.876897ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:56:19.91676Z","caller":"traceutil/trace.go:171","msg":"trace[1630036928] transaction","detail":"{read_only:false; response_revision:25588; number_of_response:1; }","duration":"153.328646ms","start":"2026-05-29T02:56:19.763424Z","end":"2026-05-29T02:56:19.916752Z","steps":["trace[1630036928] 'process raft request' (duration: 153.169893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:56:19.91691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.123832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-29T02:56:19.916932Z","caller":"traceutil/trace.go:171","msg":"trace[804513189] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:25589; }","duration":"140.187142ms","start":"2026-05-29T02:56:19.776739Z","end":"2026-05-29T02:56:19.916926Z","steps":["trace[804513189] 'agreement among raft nodes before linearized reading' (duration: 140.137961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:56:19.917075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.245764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-29T02:56:19.917099Z","caller":"traceutil/trace.go:171","msg":"trace[1551728029] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:25589; }","duration":"140.280975ms","start":"2026-05-29T02:56:19.776813Z","end":"2026-05-29T02:56:19.917094Z","steps":["trace[1551728029] 'agreement among raft nodes before linearized reading' (duration: 140.247504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:56:19.917378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.51947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-29T02:56:19.917438Z","caller":"traceutil/trace.go:171","msg":"trace[2098496994] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:25589; }","duration":"140.590681ms","start":"2026-05-29T02:56:19.776833Z","end":"2026-05-29T02:56:19.917424Z","steps":["trace[2098496994] 'agreement among raft nodes before linearized reading' (duration: 140.50468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T02:56:19.917545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.102341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"info","ts":"2026-05-29T02:56:19.917572Z","caller":"traceutil/trace.go:171","msg":"trace[1520523212] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:25589; }","duration":"133.159211ms","start":"2026-05-29T02:56:19.784407Z","end":"2026-05-29T02:56:19.917566Z","steps":["trace[1520523212] 'agreement among raft nodes before linearized reading' (duration: 133.10415ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T02:57:03.58813Z","caller":"traceutil/trace.go:171","msg":"trace[917119646] transaction","detail":"{read_only:false; response_revision:26006; number_of_response:1; }","duration":"100.357421ms","start":"2026-05-29T02:57:03.487727Z","end":"2026-05-29T02:57:03.588085Z","steps":["trace[917119646] 'process raft request' (duration: 67.197343ms)","trace[917119646] 'compare' (duration: 32.992664ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T02:57:05.840887Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068e9.wal"} {"level":"info","ts":"2026-05-29T02:57:43.771783Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23496} {"level":"info","ts":"2026-05-29T02:57:43.908799Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23496,"took":"133.886376ms","hash":3985962605,"current-db-size-bytes":62349312,"current-db-size":"62 MB","current-db-size-in-use-bytes":37003264,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-05-29T02:57:43.908863Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3985962605,"revision":23496,"compact-revision":20346} {"level":"info","ts":"2026-05-29T03:02:43.780728Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26383} {"level":"info","ts":"2026-05-29T03:02:43.92563Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26383,"took":"141.073769ms","hash":3722566299,"current-db-size-bytes":62349312,"current-db-size":"62 MB","current-db-size-in-use-bytes":33648640,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-29T03:02:43.92571Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3722566299,"revision":26383,"compact-revision":23496} {"level":"info","ts":"2026-05-29T03:03:01.592895Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f170c75a1d97cc","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-29T03:03:01.59943Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-29T03:03:01.59952Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-29T03:07:43.806373Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28957} {"level":"info","ts":"2026-05-29T03:07:43.943709Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28957,"took":"133.815934ms","hash":3785004709,"current-db-size-bytes":62349312,"current-db-size":"62 MB","current-db-size-in-use-bytes":31137792,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-29T03:07:43.954037Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3785004709,"revision":28957,"compact-revision":26383} {"level":"info","ts":"2026-05-29T03:12:43.815466Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31211} {"level":"info","ts":"2026-05-29T03:12:43.951822Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31211,"took":"130.768802ms","hash":190305038,"current-db-size-bytes":62349312,"current-db-size":"62 MB","current-db-size-in-use-bytes":31395840,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-29T03:12:43.951883Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":190305038,"revision":31211,"compact-revision":28957} {"level":"info","ts":"2026-05-29T03:17:43.820778Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33509} {"level":"info","ts":"2026-05-29T03:17:43.972814Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33509,"took":"146.596101ms","hash":1885652527,"current-db-size-bytes":63393792,"current-db-size":"63 MB","current-db-size-in-use-bytes":32251904,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-29T03:17:43.97287Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1885652527,"revision":33509,"compact-revision":31211} {"level":"info","ts":"2026-05-29T03:22:43.829531Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35772} {"level":"info","ts":"2026-05-29T03:22:44.008243Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35772,"took":"173.280757ms","hash":2882194738,"current-db-size-bytes":64724992,"current-db-size":"65 MB","current-db-size-in-use-bytes":31526912,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-29T03:22:44.008336Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2882194738,"revision":35772,"compact-revision":33509} {"level":"info","ts":"2026-05-29T03:24:26.39071Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f170c75a1d97cc","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-29T03:24:26.395258Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-29T03:24:26.395331Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}