{"level":"warn","ts":"2026-05-21T13:14:38.857932Z","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-21T13:14:38.858049Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.4: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.4:2380","--initial-cluster=instance=https://199.204.45.4:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.4:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.4: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-21T13:14:38.85815Z","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-21T13:14:38.858172Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-05-21T13:14:38.858211Z","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-21T13:14:38.859213Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"]} {"level":"info","ts":"2026-05-21T13:14:38.859341Z","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.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.4: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-21T13:14:38.866216Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.555128ms"} {"level":"info","ts":"2026-05-21T13:14:38.880079Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"66f7b57d56ad567d","cluster-id":"ec267dcaac715770"} {"level":"info","ts":"2026-05-21T13:14:38.880261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=()"} {"level":"info","ts":"2026-05-21T13:14:38.880298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 0"} {"level":"info","ts":"2026-05-21T13:14:38.880319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 66f7b57d56ad567d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-21T13:14:38.880347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 1"} {"level":"info","ts":"2026-05-21T13:14:38.880428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"warn","ts":"2026-05-21T13:14:38.891474Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-21T13:14:38.899821Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-21T13:14:38.902654Z","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-21T13:14:38.907339Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"66f7b57d56ad567d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-21T13:14:38.907555Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"66f7b57d56ad567d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-21T13:14:38.907683Z","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-21T13:14:38.907752Z","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-21T13:14:38.907761Z","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-21T13:14:38.912623Z","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-21T13:14:38.912785Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-05-21T13:14:38.912883Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-05-21T13:14:38.913009Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"66f7b57d56ad567d","initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-21T13:14:38.913056Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-21T13:14:38.916846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"info","ts":"2026-05-21T13:14:38.917055Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","added-peer-id":"66f7b57d56ad567d","added-peer-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-05-21T13:14:39.581465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d is starting a new election at term 1"} {"level":"info","ts":"2026-05-21T13:14:39.581539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-21T13:14:39.581604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgPreVoteResp from 66f7b57d56ad567d at term 1"} {"level":"info","ts":"2026-05-21T13:14:39.581621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became candidate at term 2"} {"level":"info","ts":"2026-05-21T13:14:39.581629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgVoteResp from 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-05-21T13:14:39.581641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became leader at term 2"} {"level":"info","ts":"2026-05-21T13:14:39.581651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 66f7b57d56ad567d elected leader 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-05-21T13:14:39.582901Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T13:14:39.583365Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"66f7b57d56ad567d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.4:2379]}","request-path":"/0/members/66f7b57d56ad567d/attributes","cluster-id":"ec267dcaac715770","publish-timeout":"7s"} {"level":"info","ts":"2026-05-21T13:14:39.583491Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T13:14:39.58369Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-21T13:14:39.583733Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-21T13:14:39.583373Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T13:14:39.585454Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T13:14:39.585561Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T13:14:39.585586Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T13:14:39.585757Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.4:2379"} {"level":"info","ts":"2026-05-21T13:14:39.592012Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-21T13:15:16.407946Z","caller":"traceutil/trace.go:171","msg":"trace[1290876777] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"148.475217ms","start":"2026-05-21T13:15:16.259444Z","end":"2026-05-21T13:15:16.407919Z","steps":["trace[1290876777] 'process raft request' (duration: 148.349534ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:15:16.412809Z","caller":"traceutil/trace.go:171","msg":"trace[750177533] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"127.816903ms","start":"2026-05-21T13:15:16.284965Z","end":"2026-05-21T13:15:16.412782Z","steps":["trace[750177533] 'process raft request' (duration: 127.425903ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:15:16.412985Z","caller":"traceutil/trace.go:171","msg":"trace[1933585624] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"121.57298ms","start":"2026-05-21T13:15:16.291372Z","end":"2026-05-21T13:15:16.412945Z","steps":["trace[1933585624] 'process raft request' (duration: 121.179069ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:15:16.413085Z","caller":"traceutil/trace.go:171","msg":"trace[724983480] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"149.806472ms","start":"2026-05-21T13:15:16.263254Z","end":"2026-05-21T13:15:16.413061Z","steps":["trace[724983480] 'process raft request' (duration: 148.859827ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:15:16.413203Z","caller":"traceutil/trace.go:171","msg":"trace[940091679] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"112.85642ms","start":"2026-05-21T13:15:16.300307Z","end":"2026-05-21T13:15:16.413164Z","steps":["trace[940091679] 'process raft request' (duration: 112.287225ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:15:16.413243Z","caller":"traceutil/trace.go:171","msg":"trace[1755496234] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"149.762791ms","start":"2026-05-21T13:15:16.263465Z","end":"2026-05-21T13:15:16.413227Z","steps":["trace[1755496234] 'process raft request' (duration: 148.828467ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:15:16.413443Z","caller":"traceutil/trace.go:171","msg":"trace[1998455781] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"127.822503ms","start":"2026-05-21T13:15:16.285567Z","end":"2026-05-21T13:15:16.413389Z","steps":["trace[1998455781] 'process raft request' (duration: 126.91338ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:15:16.413391Z","caller":"traceutil/trace.go:171","msg":"trace[1170311906] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"127.124065ms","start":"2026-05-21T13:15:16.286217Z","end":"2026-05-21T13:15:16.413341Z","steps":["trace[1170311906] 'process raft request' (duration: 126.298073ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:15:16.413557Z","caller":"traceutil/trace.go:171","msg":"trace[1402132960] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"128.558593ms","start":"2026-05-21T13:15:16.284884Z","end":"2026-05-21T13:15:16.413442Z","steps":["trace[1402132960] 'process raft request' (duration: 127.458894ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:15:22.426772Z","caller":"traceutil/trace.go:171","msg":"trace[61100675] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"147.481911ms","start":"2026-05-21T13:15:22.279259Z","end":"2026-05-21T13:15:22.426741Z","steps":["trace[61100675] 'process raft request' (duration: 145.892549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:15:22.640881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.704647ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T13:15:22.641142Z","caller":"traceutil/trace.go:171","msg":"trace[664232313] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:603; }","duration":"146.183906ms","start":"2026-05-21T13:15:22.494936Z","end":"2026-05-21T13:15:22.64112Z","steps":["trace[664232313] 'read index received' (duration: 19.515063ms)","trace[664232313] 'applied index is now lower than readState.Index' (duration: 126.666263ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:15:22.641263Z","caller":"traceutil/trace.go:171","msg":"trace[2103438918] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"158.887361ms","start":"2026-05-21T13:15:22.482318Z","end":"2026-05-21T13:15:22.641205Z","steps":["trace[2103438918] 'process raft request' (duration: 158.724366ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:15:22.641334Z","caller":"traceutil/trace.go:171","msg":"trace[1820296349] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"196.44417ms","start":"2026-05-21T13:15:22.444838Z","end":"2026-05-21T13:15:22.641283Z","steps":["trace[1820296349] 'process raft request' (duration: 69.52497ms)","trace[1820296349] 'compare' (duration: 125.533603ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T13:15:22.641331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.393503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:15:22.641569Z","caller":"traceutil/trace.go:171","msg":"trace[38430198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:582; }","duration":"146.630679ms","start":"2026-05-21T13:15:22.494881Z","end":"2026-05-21T13:15:22.641512Z","steps":["trace[38430198] 'agreement among raft nodes before linearized reading' (duration: 146.353261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:15:23.302647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.229427ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:15:23.30286Z","caller":"traceutil/trace.go:171","msg":"trace[1262303074] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:584; }","duration":"320.463313ms","start":"2026-05-21T13:15:22.982376Z","end":"2026-05-21T13:15:23.302839Z","steps":["trace[1262303074] 'range keys from in-memory index tree' (duration: 320.207846ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:15:23.321526Z","caller":"traceutil/trace.go:171","msg":"trace[1761147055] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"227.373403ms","start":"2026-05-21T13:15:23.09413Z","end":"2026-05-21T13:15:23.321504Z","steps":["trace[1761147055] 'process raft request' (duration: 225.414551ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:16:05.803167Z","caller":"traceutil/trace.go:171","msg":"trace[681112533] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"117.058541ms","start":"2026-05-21T13:16:05.686083Z","end":"2026-05-21T13:16:05.803142Z","steps":["trace[681112533] 'process raft request' (duration: 116.837685ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:16:27.154343Z","caller":"traceutil/trace.go:171","msg":"trace[713780296] transaction","detail":"{read_only:false; response_revision:1407; number_of_response:1; }","duration":"137.38475ms","start":"2026-05-21T13:16:27.016929Z","end":"2026-05-21T13:16:27.154314Z","steps":["trace[713780296] 'process raft request' (duration: 137.192785ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:16:51.338299Z","caller":"traceutil/trace.go:171","msg":"trace[663059845] linearizableReadLoop","detail":"{readStateIndex:1886; appliedIndex:1885; }","duration":"266.27392ms","start":"2026-05-21T13:16:51.072002Z","end":"2026-05-21T13:16:51.338276Z","steps":["trace[663059845] 'read index received' (duration: 266.101815ms)","trace[663059845] 'applied index is now lower than readState.Index' (duration: 171.465µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:16:51.33838Z","caller":"traceutil/trace.go:171","msg":"trace[1711455236] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"353.245564ms","start":"2026-05-21T13:16:50.985095Z","end":"2026-05-21T13:16:51.33834Z","steps":["trace[1711455236] 'process raft request' (duration: 353.029678ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:16:51.338537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:16:50.985071Z","time spent":"353.381288ms","remote":"127.0.0.1:47088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10968,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T13:16:51.338637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.607369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-21T13:16:51.338777Z","caller":"traceutil/trace.go:171","msg":"trace[859611678] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1817; }","duration":"266.754623ms","start":"2026-05-21T13:16:51.071953Z","end":"2026-05-21T13:16:51.338708Z","steps":["trace[859611678] 'agreement among raft nodes before linearized reading' (duration: 266.434874ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:16:51.348862Z","caller":"traceutil/trace.go:171","msg":"trace[392891664] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"138.610584ms","start":"2026-05-21T13:16:51.210231Z","end":"2026-05-21T13:16:51.348841Z","steps":["trace[392891664] 'process raft request' (duration: 138.46548ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:16:51.34918Z","caller":"traceutil/trace.go:171","msg":"trace[629066353] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"107.085094ms","start":"2026-05-21T13:16:51.242067Z","end":"2026-05-21T13:16:51.349152Z","steps":["trace[629066353] 'process raft request' (duration: 106.741874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:16:51.349504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.717678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T13:16:51.349542Z","caller":"traceutil/trace.go:171","msg":"trace[569180500] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1820; }","duration":"234.80768ms","start":"2026-05-21T13:16:51.114723Z","end":"2026-05-21T13:16:51.349531Z","steps":["trace[569180500] 'agreement among raft nodes before linearized reading' (duration: 234.715618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:16:51.67963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.846086ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T13:16:51.679918Z","caller":"traceutil/trace.go:171","msg":"trace[2084584159] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"335.866099ms","start":"2026-05-21T13:16:51.344024Z","end":"2026-05-21T13:16:51.67989Z","steps":["trace[2084584159] 'process raft request' (duration: 35.637363ms)","trace[2084584159] 'compare' (duration: 299.731242ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:16:51.680262Z","caller":"traceutil/trace.go:171","msg":"trace[307114542] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"335.054967ms","start":"2026-05-21T13:16:51.345177Z","end":"2026-05-21T13:16:51.680232Z","steps":["trace[307114542] 'process raft request' (duration: 334.681567ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:16:51.679944Z","caller":"traceutil/trace.go:171","msg":"trace[275687475] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"335.023536ms","start":"2026-05-21T13:16:51.344902Z","end":"2026-05-21T13:16:51.679925Z","steps":["trace[275687475] 'process raft request' (duration: 334.856741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:16:51.680365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:16:51.345167Z","time spent":"335.144479ms","remote":"127.0.0.1:47058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T13:16:51.68038Z","caller":"traceutil/trace.go:171","msg":"trace[1079887075] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"334.393009ms","start":"2026-05-21T13:16:51.345967Z","end":"2026-05-21T13:16:51.68036Z","steps":["trace[1079887075] 'process raft request' (duration: 334.168023ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:16:51.680564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:16:51.345943Z","time spent":"334.536923ms","remote":"127.0.0.1:47172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T13:16:51.680578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:16:51.34489Z","time spent":"335.5085ms","remote":"127.0.0.1:47172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T13:16:51.680643Z","caller":"traceutil/trace.go:171","msg":"trace[514269976] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"334.533153ms","start":"2026-05-21T13:16:51.346054Z","end":"2026-05-21T13:16:51.680587Z","steps":["trace[514269976] 'process raft request' (duration: 334.204684ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:16:51.680773Z","caller":"traceutil/trace.go:171","msg":"trace[382335385] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"327.838599ms","start":"2026-05-21T13:16:51.352878Z","end":"2026-05-21T13:16:51.680717Z","steps":["trace[382335385] 'process raft request' (duration: 327.678145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:16:51.680868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:16:51.346049Z","time spent":"334.695077ms","remote":"127.0.0.1:47058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T13:16:51.680895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.656718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-65232c8f-1dbb-40cb-aa65-3886855b81e5\" ","response":"range_response_count:1 size:3625"} {"level":"info","ts":"2026-05-21T13:16:51.680978Z","caller":"traceutil/trace.go:171","msg":"trace[1456200687] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-65232c8f-1dbb-40cb-aa65-3886855b81e5; range_end:; response_count:1; response_revision:1826; }","duration":"337.779511ms","start":"2026-05-21T13:16:51.343178Z","end":"2026-05-21T13:16:51.680957Z","steps":["trace[1456200687] 'agreement among raft nodes before linearized reading' (duration: 337.536254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:16:51.681037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:16:51.343155Z","time spent":"337.870164ms","remote":"127.0.0.1:47088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3648,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-65232c8f-1dbb-40cb-aa65-3886855b81e5\" "} {"level":"warn","ts":"2026-05-21T13:16:51.680992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:16:51.352856Z","time spent":"328.002244ms","remote":"127.0.0.1:47166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T13:16:51.680631Z","caller":"traceutil/trace.go:171","msg":"trace[1701930909] linearizableReadLoop","detail":"{readStateIndex:1894; appliedIndex:1889; }","duration":"331.305484ms","start":"2026-05-21T13:16:51.349303Z","end":"2026-05-21T13:16:51.680608Z","steps":["trace[1701930909] 'read index received' (duration: 30.656057ms)","trace[1701930909] 'applied index is now lower than readState.Index' (duration: 300.646977ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T13:16:51.681622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.562819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-21T13:16:51.681637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.508575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:16:51.681678Z","caller":"traceutil/trace.go:171","msg":"trace[303399553] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1826; }","duration":"269.684942ms","start":"2026-05-21T13:16:51.411981Z","end":"2026-05-21T13:16:51.681665Z","steps":["trace[303399553] 'agreement among raft nodes before linearized reading' (duration: 269.58823ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:16:51.68169Z","caller":"traceutil/trace.go:171","msg":"trace[2066996779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1826; }","duration":"185.602278ms","start":"2026-05-21T13:16:51.49607Z","end":"2026-05-21T13:16:51.681672Z","steps":["trace[2066996779] 'agreement among raft nodes before linearized reading' (duration: 185.504645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:16:51.68156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:16:51.343994Z","time spent":"336.26818ms","remote":"127.0.0.1:47166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T13:17:06.427896Z","caller":"traceutil/trace.go:171","msg":"trace[148119062] transaction","detail":"{read_only:false; response_revision:1949; number_of_response:1; }","duration":"276.54555ms","start":"2026-05-21T13:17:06.151325Z","end":"2026-05-21T13:17:06.42787Z","steps":["trace[148119062] 'process raft request' (duration: 276.18809ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:17:46.070916Z","caller":"traceutil/trace.go:171","msg":"trace[81945429] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"122.897343ms","start":"2026-05-21T13:17:45.94798Z","end":"2026-05-21T13:17:46.070878Z","steps":["trace[81945429] 'process raft request' (duration: 122.570384ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:18:20.812453Z","caller":"traceutil/trace.go:171","msg":"trace[1012836416] transaction","detail":"{read_only:false; response_revision:2537; number_of_response:1; }","duration":"120.684912ms","start":"2026-05-21T13:18:20.691745Z","end":"2026-05-21T13:18:20.81243Z","steps":["trace[1012836416] 'process raft request' (duration: 119.561331ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:20:25.452312Z","caller":"traceutil/trace.go:171","msg":"trace[357009117] transaction","detail":"{read_only:false; response_revision:3226; number_of_response:1; }","duration":"218.067105ms","start":"2026-05-21T13:20:25.234225Z","end":"2026-05-21T13:20:25.452292Z","steps":["trace[357009117] 'process raft request' (duration: 217.000565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:20:26.097263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.033367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-21T13:20:26.097351Z","caller":"traceutil/trace.go:171","msg":"trace[1983414612] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3226; }","duration":"315.16115ms","start":"2026-05-21T13:20:25.782169Z","end":"2026-05-21T13:20:26.09733Z","steps":["trace[1983414612] 'range keys from in-memory index tree' (duration: 314.873902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:20:26.097388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:20:25.782149Z","time spent":"315.230243ms","remote":"127.0.0.1:47094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-05-21T13:20:28.461738Z","caller":"traceutil/trace.go:171","msg":"trace[217893366] transaction","detail":"{read_only:false; response_revision:3240; number_of_response:1; }","duration":"135.4031ms","start":"2026-05-21T13:20:28.326305Z","end":"2026-05-21T13:20:28.461708Z","steps":["trace[217893366] 'process raft request' (duration: 135.217855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:22:59.864282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.163632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-s79vn\" ","response":"range_response_count:1 size:7816"} {"level":"info","ts":"2026-05-21T13:22:59.864355Z","caller":"traceutil/trace.go:171","msg":"trace[1520323460] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-s79vn; range_end:; response_count:1; response_revision:5172; }","duration":"106.275495ms","start":"2026-05-21T13:22:59.758064Z","end":"2026-05-21T13:22:59.864339Z","steps":["trace[1520323460] 'range keys from in-memory index tree' (duration: 106.024928ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:23:00.009986Z","caller":"traceutil/trace.go:171","msg":"trace[235913017] transaction","detail":"{read_only:false; response_revision:5173; number_of_response:1; }","duration":"136.834724ms","start":"2026-05-21T13:22:59.873114Z","end":"2026-05-21T13:23:00.009949Z","steps":["trace[235913017] 'process raft request' (duration: 136.651839ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:23:07.732419Z","caller":"traceutil/trace.go:171","msg":"trace[525285345] transaction","detail":"{read_only:false; response_revision:5311; number_of_response:1; }","duration":"200.04726ms","start":"2026-05-21T13:23:07.532345Z","end":"2026-05-21T13:23:07.732393Z","steps":["trace[525285345] 'process raft request' (duration: 145.984583ms)","trace[525285345] 'compare' (duration: 53.959144ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:23:07.748832Z","caller":"traceutil/trace.go:171","msg":"trace[543194084] transaction","detail":"{read_only:false; response_revision:5312; number_of_response:1; }","duration":"176.08317ms","start":"2026-05-21T13:23:07.5727Z","end":"2026-05-21T13:23:07.748783Z","steps":["trace[543194084] 'process raft request' (duration: 175.845204ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:23:07.976962Z","caller":"traceutil/trace.go:171","msg":"trace[1367547306] linearizableReadLoop","detail":"{readStateIndex:5513; appliedIndex:5512; }","duration":"143.123295ms","start":"2026-05-21T13:23:07.833817Z","end":"2026-05-21T13:23:07.97694Z","steps":["trace[1367547306] 'read index received' (duration: 143.081614ms)","trace[1367547306] 'applied index is now lower than readState.Index' (duration: 40.401µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:23:07.977067Z","caller":"traceutil/trace.go:171","msg":"trace[1777646104] transaction","detail":"{read_only:false; response_revision:5318; number_of_response:1; }","duration":"145.697656ms","start":"2026-05-21T13:23:07.831358Z","end":"2026-05-21T13:23:07.977056Z","steps":["trace[1777646104] 'process raft request' (duration: 145.440899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:23:07.977173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.326131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:23:07.977276Z","caller":"traceutil/trace.go:171","msg":"trace[913881595] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5318; }","duration":"143.460124ms","start":"2026-05-21T13:23:07.833791Z","end":"2026-05-21T13:23:07.977251Z","steps":["trace[913881595] 'agreement among raft nodes before linearized reading' (duration: 143.285719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:23:08.157799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.831787ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T13:23:08.157926Z","caller":"traceutil/trace.go:171","msg":"trace[1988370] transaction","detail":"{read_only:false; response_revision:5319; number_of_response:1; }","duration":"306.447339ms","start":"2026-05-21T13:23:07.851453Z","end":"2026-05-21T13:23:08.1579Z","steps":["trace[1988370] 'process raft request' (duration: 198.454937ms)","trace[1988370] 'compare' (duration: 107.638182ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:23:08.157993Z","caller":"traceutil/trace.go:171","msg":"trace[1683902125] linearizableReadLoop","detail":"{readStateIndex:5514; appliedIndex:5513; }","duration":"180.907281ms","start":"2026-05-21T13:23:07.977032Z","end":"2026-05-21T13:23:08.15794Z","steps":["trace[1683902125] 'read index received' (duration: 72.967851ms)","trace[1683902125] 'applied index is now lower than readState.Index' (duration: 107.9346ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T13:23:08.158028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:23:07.851423Z","time spent":"306.560412ms","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2581,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T13:23:08.315507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.601649ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-05-21T13:23:08.315634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.333092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:23:08.315721Z","caller":"traceutil/trace.go:171","msg":"trace[998029636] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:5319; }","duration":"367.467345ms","start":"2026-05-21T13:23:07.948227Z","end":"2026-05-21T13:23:08.315695Z","steps":["trace[998029636] 'agreement among raft nodes before linearized reading' (duration: 209.816976ms)","trace[998029636] 'count revisions from in-memory index tree' (duration: 157.517896ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T13:23:08.315775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:23:07.948211Z","time spent":"367.552297ms","remote":"127.0.0.1:56542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true "} {"level":"info","ts":"2026-05-21T13:23:08.315888Z","caller":"traceutil/trace.go:171","msg":"trace[898308678] linearizableReadLoop","detail":"{readStateIndex:5516; appliedIndex:5514; }","duration":"157.782063ms","start":"2026-05-21T13:23:08.158084Z","end":"2026-05-21T13:23:08.315866Z","steps":["trace[898308678] 'read index received' (duration: 150.788523ms)","trace[898308678] 'applied index is now lower than readState.Index' (duration: 6.99199ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:23:08.315965Z","caller":"traceutil/trace.go:171","msg":"trace[85862794] transaction","detail":"{read_only:false; response_revision:5320; number_of_response:1; }","duration":"251.003914ms","start":"2026-05-21T13:23:08.064938Z","end":"2026-05-21T13:23:08.315942Z","steps":["trace[85862794] 'process raft request' (duration: 250.703146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:23:08.317131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.022573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-21T13:23:08.317252Z","caller":"traceutil/trace.go:171","msg":"trace[1120109181] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5320; }","duration":"178.160097ms","start":"2026-05-21T13:23:08.139047Z","end":"2026-05-21T13:23:08.317207Z","steps":["trace[1120109181] 'agreement among raft nodes before linearized reading' (duration: 176.925073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:23:08.317479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.03632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:23:08.317556Z","caller":"traceutil/trace.go:171","msg":"trace[1987627557] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5320; }","duration":"337.142612ms","start":"2026-05-21T13:23:07.980384Z","end":"2026-05-21T13:23:08.317526Z","steps":["trace[1987627557] 'agreement among raft nodes before linearized reading' (duration: 335.828827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:23:08.31759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:23:07.980362Z","time spent":"337.219265ms","remote":"127.0.0.1:47094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-05-21T13:23:08.325302Z","caller":"traceutil/trace.go:171","msg":"trace[519324359] transaction","detail":"{read_only:false; response_revision:5322; number_of_response:1; }","duration":"154.961766ms","start":"2026-05-21T13:23:08.170308Z","end":"2026-05-21T13:23:08.32527Z","steps":["trace[519324359] 'process raft request' (duration: 154.859363ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:23:08.325526Z","caller":"traceutil/trace.go:171","msg":"trace[1326802986] transaction","detail":"{read_only:false; response_revision:5321; number_of_response:1; }","duration":"161.861864ms","start":"2026-05-21T13:23:08.163651Z","end":"2026-05-21T13:23:08.325512Z","steps":["trace[1326802986] 'process raft request' (duration: 159.778857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:23:08.325576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.865617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T13:23:08.325609Z","caller":"traceutil/trace.go:171","msg":"trace[1010404680] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:5322; }","duration":"125.928458ms","start":"2026-05-21T13:23:08.199667Z","end":"2026-05-21T13:23:08.325596Z","steps":["trace[1010404680] 'agreement among raft nodes before linearized reading' (duration: 125.875447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:23:08.325311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.281103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-21T13:23:08.325785Z","caller":"traceutil/trace.go:171","msg":"trace[226119032] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5322; }","duration":"163.801297ms","start":"2026-05-21T13:23:08.161972Z","end":"2026-05-21T13:23:08.325773Z","steps":["trace[226119032] 'agreement among raft nodes before linearized reading' (duration: 163.242212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:23:08.32588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.779736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-21T13:23:08.325927Z","caller":"traceutil/trace.go:171","msg":"trace[1134188476] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5322; }","duration":"163.871859ms","start":"2026-05-21T13:23:08.162044Z","end":"2026-05-21T13:23:08.325916Z","steps":["trace[1134188476] 'agreement among raft nodes before linearized reading' (duration: 163.720645ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:23:21.140771Z","caller":"traceutil/trace.go:171","msg":"trace[659746452] transaction","detail":"{read_only:false; response_revision:5503; number_of_response:1; }","duration":"153.492506ms","start":"2026-05-21T13:23:20.987255Z","end":"2026-05-21T13:23:21.140748Z","steps":["trace[659746452] 'process raft request' (duration: 90.060364ms)","trace[659746452] 'compare' (duration: 63.289438ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:23:21.141192Z","caller":"traceutil/trace.go:171","msg":"trace[175206581] transaction","detail":"{read_only:false; response_revision:5504; number_of_response:1; }","duration":"153.865406ms","start":"2026-05-21T13:23:20.987295Z","end":"2026-05-21T13:23:21.14116Z","steps":["trace[175206581] 'process raft request' (duration: 153.416774ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:24:20.709739Z","caller":"traceutil/trace.go:171","msg":"trace[2000166257] transaction","detail":"{read_only:false; response_revision:5995; number_of_response:1; }","duration":"119.660999ms","start":"2026-05-21T13:24:20.590001Z","end":"2026-05-21T13:24:20.709662Z","steps":["trace[2000166257] 'process raft request' (duration: 119.454073ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:24:39.62338Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2986} {"level":"info","ts":"2026-05-21T13:24:39.696176Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2986,"took":"68.288956ms","hash":3691511128,"current-db-size-bytes":57802752,"current-db-size":"58 MB","current-db-size-in-use-bytes":34598912,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-05-21T13:24:39.696237Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3691511128,"revision":2986,"compact-revision":-1} {"level":"info","ts":"2026-05-21T13:29:39.631972Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6150} {"level":"info","ts":"2026-05-21T13:29:39.721515Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6150,"took":"87.93023ms","hash":1921719892,"current-db-size-bytes":57802752,"current-db-size":"58 MB","current-db-size-in-use-bytes":23834624,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-21T13:29:39.721586Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1921719892,"revision":6150,"compact-revision":2986} {"level":"info","ts":"2026-05-21T13:29:40.386757Z","caller":"traceutil/trace.go:171","msg":"trace[1139176531] transaction","detail":"{read_only:false; response_revision:8322; number_of_response:1; }","duration":"100.740869ms","start":"2026-05-21T13:29:40.285983Z","end":"2026-05-21T13:29:40.386723Z","steps":["trace[1139176531] 'process raft request' (duration: 60.593993ms)","trace[1139176531] 'compare' (duration: 40.011002ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:29:40.691364Z","caller":"traceutil/trace.go:171","msg":"trace[1997792777] linearizableReadLoop","detail":"{readStateIndex:8617; appliedIndex:8616; }","duration":"223.21947ms","start":"2026-05-21T13:29:40.468108Z","end":"2026-05-21T13:29:40.691327Z","steps":["trace[1997792777] 'read index received' (duration: 222.884411ms)","trace[1997792777] 'applied index is now lower than readState.Index' (duration: 333.279µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:29:40.691431Z","caller":"traceutil/trace.go:171","msg":"trace[227407754] transaction","detail":"{read_only:false; response_revision:8323; number_of_response:1; }","duration":"233.582383ms","start":"2026-05-21T13:29:40.457805Z","end":"2026-05-21T13:29:40.691387Z","steps":["trace[227407754] 'process raft request' (duration: 233.294005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:29:40.691767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.629251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-d8k9t\" ","response":"range_response_count:1 size:6515"} {"level":"warn","ts":"2026-05-21T13:29:40.691815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.301359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T13:29:40.691831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.567388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-21T13:29:40.691873Z","caller":"traceutil/trace.go:171","msg":"trace[109001416] range","detail":"{range_begin:/registry/pods/openstack/keepalived-d8k9t; range_end:; response_count:1; response_revision:8323; }","duration":"223.804606ms","start":"2026-05-21T13:29:40.468044Z","end":"2026-05-21T13:29:40.691849Z","steps":["trace[109001416] 'agreement among raft nodes before linearized reading' (duration: 223.480437ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:29:40.691922Z","caller":"traceutil/trace.go:171","msg":"trace[1176014654] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8323; }","duration":"209.738982ms","start":"2026-05-21T13:29:40.482164Z","end":"2026-05-21T13:29:40.691903Z","steps":["trace[1176014654] 'agreement among raft nodes before linearized reading' (duration: 209.433034ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:29:40.691922Z","caller":"traceutil/trace.go:171","msg":"trace[186254447] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8323; }","duration":"195.454953ms","start":"2026-05-21T13:29:40.496437Z","end":"2026-05-21T13:29:40.691892Z","steps":["trace[186254447] 'agreement among raft nodes before linearized reading' (duration: 195.285728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:29:42.106298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.71843ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T13:29:42.106633Z","caller":"traceutil/trace.go:171","msg":"trace[424219958] transaction","detail":"{read_only:false; response_revision:8328; number_of_response:1; }","duration":"304.959371ms","start":"2026-05-21T13:29:41.801644Z","end":"2026-05-21T13:29:42.106604Z","steps":["trace[424219958] 'process raft request' (duration: 141.82782ms)","trace[424219958] 'compare' (duration: 162.574706ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T13:29:42.10686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:29:41.801617Z","time spent":"305.147936ms","remote":"127.0.0.1:47166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T13:29:42.537673Z","caller":"traceutil/trace.go:171","msg":"trace[1788642247] transaction","detail":"{read_only:false; response_revision:8332; number_of_response:1; }","duration":"144.76261ms","start":"2026-05-21T13:29:42.392886Z","end":"2026-05-21T13:29:42.537649Z","steps":["trace[1788642247] 'process raft request' (duration: 128.363713ms)","trace[1788642247] 'compare' (duration: 16.126759ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:30:10.054618Z","caller":"traceutil/trace.go:171","msg":"trace[294889182] linearizableReadLoop","detail":"{readStateIndex:8834; appliedIndex:8832; }","duration":"142.93866ms","start":"2026-05-21T13:30:09.911652Z","end":"2026-05-21T13:30:10.054591Z","steps":["trace[294889182] 'read index received' (duration: 128.318491ms)","trace[294889182] 'applied index is now lower than readState.Index' (duration: 14.619039ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T13:30:10.054901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.207827ms","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-21T13:30:10.054977Z","caller":"traceutil/trace.go:171","msg":"trace[536307699] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8532; }","duration":"143.339771ms","start":"2026-05-21T13:30:09.911622Z","end":"2026-05-21T13:30:10.054962Z","steps":["trace[536307699] 'agreement among raft nodes before linearized reading' (duration: 143.084724ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:30:10.054976Z","caller":"traceutil/trace.go:171","msg":"trace[1609066074] transaction","detail":"{read_only:false; response_revision:8531; number_of_response:1; }","duration":"147.665859ms","start":"2026-05-21T13:30:09.907264Z","end":"2026-05-21T13:30:10.05493Z","steps":["trace[1609066074] 'process raft request' (duration: 132.690241ms)","trace[1609066074] 'compare' (duration: 14.353841ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:30:10.055667Z","caller":"traceutil/trace.go:171","msg":"trace[1401776043] transaction","detail":"{read_only:false; response_revision:8532; number_of_response:1; }","duration":"147.112524ms","start":"2026-05-21T13:30:09.907678Z","end":"2026-05-21T13:30:10.05479Z","steps":["trace[1401776043] 'process raft request' (duration: 146.852206ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:30:12.813055Z","caller":"traceutil/trace.go:171","msg":"trace[871869293] transaction","detail":"{read_only:false; response_revision:8547; number_of_response:1; }","duration":"185.141561ms","start":"2026-05-21T13:30:12.62782Z","end":"2026-05-21T13:30:12.812962Z","steps":["trace[871869293] 'process raft request' (duration: 178.765867ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:30:12.869529Z","caller":"traceutil/trace.go:171","msg":"trace[2137739981] transaction","detail":"{read_only:false; response_revision:8548; number_of_response:1; }","duration":"126.649226ms","start":"2026-05-21T13:30:12.742846Z","end":"2026-05-21T13:30:12.869495Z","steps":["trace[2137739981] 'process raft request' (duration: 126.42989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:30:22.699597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.248365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.4\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-05-21T13:30:22.699733Z","caller":"traceutil/trace.go:171","msg":"trace[342193908] range","detail":"{range_begin:/registry/masterleases/199.204.45.4; range_end:; response_count:1; response_revision:8643; }","duration":"103.423529ms","start":"2026-05-21T13:30:22.59628Z","end":"2026-05-21T13:30:22.699703Z","steps":["trace[342193908] 'agreement among raft nodes before linearized reading' (duration: 103.1045ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:30:36.802669Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002378.wal"} {"level":"info","ts":"2026-05-21T13:32:15.431881Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"66f7b57d56ad567d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-21T13:32:15.437802Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-21T13:32:15.437966Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-21T13:34:39.638972Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8314} {"level":"info","ts":"2026-05-21T13:34:39.713198Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8314,"took":"72.089098ms","hash":2572122958,"current-db-size-bytes":57802752,"current-db-size":"58 MB","current-db-size-in-use-bytes":25387008,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-05-21T13:34:39.71326Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2572122958,"revision":8314,"compact-revision":6150} {"level":"info","ts":"2026-05-21T13:35:34.257938Z","caller":"traceutil/trace.go:171","msg":"trace[1394014821] linearizableReadLoop","detail":"{readStateIndex:11686; appliedIndex:11685; }","duration":"129.150934ms","start":"2026-05-21T13:35:34.128756Z","end":"2026-05-21T13:35:34.257907Z","steps":["trace[1394014821] 'read index received' (duration: 129.049502ms)","trace[1394014821] 'applied index is now lower than readState.Index' (duration: 100.262µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:35:34.258006Z","caller":"traceutil/trace.go:171","msg":"trace[1880274310] transaction","detail":"{read_only:false; response_revision:11299; number_of_response:1; }","duration":"129.514914ms","start":"2026-05-21T13:35:34.128451Z","end":"2026-05-21T13:35:34.257966Z","steps":["trace[1880274310] 'process raft request' (duration: 129.239656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:35:34.258275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.453172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-05-21T13:35:34.258427Z","caller":"traceutil/trace.go:171","msg":"trace[539269776] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11299; }","duration":"129.675318ms","start":"2026-05-21T13:35:34.1287Z","end":"2026-05-21T13:35:34.258375Z","steps":["trace[539269776] 'agreement among raft nodes before linearized reading' (duration: 129.349759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:35:34.271292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.769763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T13:35:34.271328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.48282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:35:34.271357Z","caller":"traceutil/trace.go:171","msg":"trace[286324687] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11300; }","duration":"128.866096ms","start":"2026-05-21T13:35:34.142475Z","end":"2026-05-21T13:35:34.271341Z","steps":["trace[286324687] 'agreement among raft nodes before linearized reading' (duration: 128.737852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:35:34.271362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.348939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:35:34.271392Z","caller":"traceutil/trace.go:171","msg":"trace[633964691] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11300; }","duration":"142.566822ms","start":"2026-05-21T13:35:34.128807Z","end":"2026-05-21T13:35:34.271374Z","steps":["trace[633964691] 'agreement among raft nodes before linearized reading' (duration: 142.451659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:35:34.271378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.295091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:35:34.271495Z","caller":"traceutil/trace.go:171","msg":"trace[529061830] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11300; }","duration":"124.438174ms","start":"2026-05-21T13:35:34.147032Z","end":"2026-05-21T13:35:34.27147Z","steps":["trace[529061830] 'agreement among raft nodes before linearized reading' (duration: 124.168047ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:35:34.271408Z","caller":"traceutil/trace.go:171","msg":"trace[559544776] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11300; }","duration":"125.483873ms","start":"2026-05-21T13:35:34.145911Z","end":"2026-05-21T13:35:34.271395Z","steps":["trace[559544776] 'agreement among raft nodes before linearized reading' (duration: 125.303408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:35:34.271542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.38882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-05-21T13:35:34.271566Z","caller":"traceutil/trace.go:171","msg":"trace[129954955] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11300; }","duration":"125.433372ms","start":"2026-05-21T13:35:34.146127Z","end":"2026-05-21T13:35:34.271561Z","steps":["trace[129954955] 'agreement among raft nodes before linearized reading' (duration: 125.327869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:35:34.271555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.977878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-21T13:35:34.271596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.443342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:35:34.271572Z","caller":"traceutil/trace.go:171","msg":"trace[263253901] transaction","detail":"{read_only:false; response_revision:11300; number_of_response:1; }","duration":"124.218349ms","start":"2026-05-21T13:35:34.147305Z","end":"2026-05-21T13:35:34.271524Z","steps":["trace[263253901] 'process raft request' (duration: 123.748276ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:35:34.271639Z","caller":"traceutil/trace.go:171","msg":"trace[466471311] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11300; }","duration":"118.098921ms","start":"2026-05-21T13:35:34.153519Z","end":"2026-05-21T13:35:34.271618Z","steps":["trace[466471311] 'agreement among raft nodes before linearized reading' (duration: 117.823713ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:35:34.271442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.910278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:35:34.271625Z","caller":"traceutil/trace.go:171","msg":"trace[69272346] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11300; }","duration":"125.472243ms","start":"2026-05-21T13:35:34.146146Z","end":"2026-05-21T13:35:34.271618Z","steps":["trace[69272346] 'agreement among raft nodes before linearized reading' (duration: 125.431881ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:35:34.271698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.63735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:35:34.271728Z","caller":"traceutil/trace.go:171","msg":"trace[1709032580] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11300; }","duration":"125.184226ms","start":"2026-05-21T13:35:34.146505Z","end":"2026-05-21T13:35:34.271689Z","steps":["trace[1709032580] 'agreement among raft nodes before linearized reading' (duration: 124.921688ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:35:34.271774Z","caller":"traceutil/trace.go:171","msg":"trace[2117905406] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11300; }","duration":"124.756463ms","start":"2026-05-21T13:35:34.146997Z","end":"2026-05-21T13:35:34.271753Z","steps":["trace[2117905406] 'agreement among raft nodes before linearized reading' (duration: 124.605469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:35:34.271722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.199611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-05-21T13:35:34.27185Z","caller":"traceutil/trace.go:171","msg":"trace[680144420] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11300; }","duration":"123.379276ms","start":"2026-05-21T13:35:34.148458Z","end":"2026-05-21T13:35:34.271837Z","steps":["trace[680144420] 'agreement among raft nodes before linearized reading' (duration: 123.1874ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:35:36.081565Z","caller":"traceutil/trace.go:171","msg":"trace[167740334] transaction","detail":"{read_only:false; response_revision:11308; number_of_response:1; }","duration":"144.447994ms","start":"2026-05-21T13:35:35.937088Z","end":"2026-05-21T13:35:36.081536Z","steps":["trace[167740334] 'process raft request' (duration: 144.262119ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:35:36.091093Z","caller":"traceutil/trace.go:171","msg":"trace[402430643] transaction","detail":"{read_only:false; response_revision:11309; number_of_response:1; }","duration":"143.490407ms","start":"2026-05-21T13:35:35.947371Z","end":"2026-05-21T13:35:36.090861Z","steps":["trace[402430643] 'process raft request' (duration: 143.365334ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:39:39.644889Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10802} {"level":"info","ts":"2026-05-21T13:39:39.722595Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10802,"took":"75.966995ms","hash":797903436,"current-db-size-bytes":57802752,"current-db-size":"58 MB","current-db-size-in-use-bytes":26853376,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-21T13:39:39.722658Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":797903436,"revision":10802,"compact-revision":8314} {"level":"info","ts":"2026-05-21T13:41:34.762934Z","caller":"traceutil/trace.go:171","msg":"trace[1183441415] transaction","detail":"{read_only:false; response_revision:13745; number_of_response:1; }","duration":"100.640638ms","start":"2026-05-21T13:41:34.662266Z","end":"2026-05-21T13:41:34.762906Z","steps":["trace[1183441415] 'process raft request' (duration: 100.464713ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:41:34.764124Z","caller":"traceutil/trace.go:171","msg":"trace[376356109] transaction","detail":"{read_only:false; response_revision:13746; number_of_response:1; }","duration":"101.392268ms","start":"2026-05-21T13:41:34.662698Z","end":"2026-05-21T13:41:34.764091Z","steps":["trace[376356109] 'process raft request' (duration: 101.234164ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:42:11.612888Z","caller":"traceutil/trace.go:171","msg":"trace[2099533905] linearizableReadLoop","detail":"{readStateIndex:14433; appliedIndex:14432; }","duration":"116.791307ms","start":"2026-05-21T13:42:11.496074Z","end":"2026-05-21T13:42:11.612865Z","steps":["trace[2099533905] 'read index received' (duration: 116.683965ms)","trace[2099533905] 'applied index is now lower than readState.Index' (duration: 106.792µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:42:11.612941Z","caller":"traceutil/trace.go:171","msg":"trace[728538060] transaction","detail":"{read_only:false; response_revision:13952; number_of_response:1; }","duration":"154.091746ms","start":"2026-05-21T13:42:11.458823Z","end":"2026-05-21T13:42:11.612915Z","steps":["trace[728538060] 'process raft request' (duration: 153.89023ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:42:11.613022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.934972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:42:11.613055Z","caller":"traceutil/trace.go:171","msg":"trace[293935877] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13952; }","duration":"117.013233ms","start":"2026-05-21T13:42:11.496034Z","end":"2026-05-21T13:42:11.613047Z","steps":["trace[293935877] 'agreement among raft nodes before linearized reading' (duration: 116.943822ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:42:11.818033Z","caller":"traceutil/trace.go:171","msg":"trace[1121402377] transaction","detail":"{read_only:false; response_revision:13953; number_of_response:1; }","duration":"200.787541ms","start":"2026-05-21T13:42:11.617223Z","end":"2026-05-21T13:42:11.818011Z","steps":["trace[1121402377] 'process raft request' (duration: 163.428222ms)","trace[1121402377] 'compare' (duration: 37.215205ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:42:11.818329Z","caller":"traceutil/trace.go:171","msg":"trace[1062478732] transaction","detail":"{read_only:false; response_revision:13954; number_of_response:1; }","duration":"201.071939ms","start":"2026-05-21T13:42:11.617248Z","end":"2026-05-21T13:42:11.81832Z","steps":["trace[1062478732] 'process raft request' (duration: 200.736189ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:42:11.818416Z","caller":"traceutil/trace.go:171","msg":"trace[1481987872] linearizableReadLoop","detail":"{readStateIndex:14437; appliedIndex:14433; }","duration":"181.042072ms","start":"2026-05-21T13:42:11.637349Z","end":"2026-05-21T13:42:11.818391Z","steps":["trace[1481987872] 'read index received' (duration: 143.24865ms)","trace[1481987872] 'applied index is now lower than readState.Index' (duration: 37.792141ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:42:11.818486Z","caller":"traceutil/trace.go:171","msg":"trace[710404217] transaction","detail":"{read_only:false; response_revision:13955; number_of_response:1; }","duration":"200.896634ms","start":"2026-05-21T13:42:11.61754Z","end":"2026-05-21T13:42:11.818437Z","steps":["trace[710404217] 'process raft request' (duration: 200.668498ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:42:11.818569Z","caller":"traceutil/trace.go:171","msg":"trace[1693079389] transaction","detail":"{read_only:false; response_revision:13956; number_of_response:1; }","duration":"196.098853ms","start":"2026-05-21T13:42:11.622417Z","end":"2026-05-21T13:42:11.818516Z","steps":["trace[1693079389] 'process raft request' (duration: 195.903278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:42:11.818665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.287888ms","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-21T13:42:11.818764Z","caller":"traceutil/trace.go:171","msg":"trace[671995467] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13956; }","duration":"181.413982ms","start":"2026-05-21T13:42:11.637325Z","end":"2026-05-21T13:42:11.818739Z","steps":["trace[671995467] 'agreement among raft nodes before linearized reading' (duration: 181.152985ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:42:13.937734Z","caller":"traceutil/trace.go:171","msg":"trace[671867986] transaction","detail":"{read_only:false; response_revision:13971; number_of_response:1; }","duration":"113.938939ms","start":"2026-05-21T13:42:13.823763Z","end":"2026-05-21T13:42:13.937702Z","steps":["trace[671867986] 'process raft request' (duration: 113.413265ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:42:13.961834Z","caller":"traceutil/trace.go:171","msg":"trace[426860033] transaction","detail":"{read_only:false; response_revision:13972; number_of_response:1; }","duration":"135.565391ms","start":"2026-05-21T13:42:13.826242Z","end":"2026-05-21T13:42:13.961807Z","steps":["trace[426860033] 'process raft request' (duration: 135.380575ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:42:13.962061Z","caller":"traceutil/trace.go:171","msg":"trace[1930790298] linearizableReadLoop","detail":"{readStateIndex:14456; appliedIndex:14452; }","duration":"133.842794ms","start":"2026-05-21T13:42:13.828203Z","end":"2026-05-21T13:42:13.962046Z","steps":["trace[1930790298] 'read index received' (duration: 109.263513ms)","trace[1930790298] 'applied index is now lower than readState.Index' (duration: 24.578021ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:42:13.962098Z","caller":"traceutil/trace.go:171","msg":"trace[531648358] transaction","detail":"{read_only:false; response_revision:13974; number_of_response:1; }","duration":"134.482831ms","start":"2026-05-21T13:42:13.827607Z","end":"2026-05-21T13:42:13.96209Z","steps":["trace[531648358] 'process raft request' (duration: 134.355548ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:42:13.962134Z","caller":"traceutil/trace.go:171","msg":"trace[829628158] transaction","detail":"{read_only:false; response_revision:13973; number_of_response:1; }","duration":"135.566091ms","start":"2026-05-21T13:42:13.826535Z","end":"2026-05-21T13:42:13.962101Z","steps":["trace[829628158] 'process raft request' (duration: 135.221281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:42:13.962207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.992668ms","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-21T13:42:13.962234Z","caller":"traceutil/trace.go:171","msg":"trace[1657497940] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13974; }","duration":"134.08506ms","start":"2026-05-21T13:42:13.828137Z","end":"2026-05-21T13:42:13.962222Z","steps":["trace[1657497940] 'agreement among raft nodes before linearized reading' (duration: 133.984747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:43:51.919084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.548971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T13:43:51.91915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.142942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18720"} {"level":"info","ts":"2026-05-21T13:43:51.919292Z","caller":"traceutil/trace.go:171","msg":"trace[1022699981] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:15157; }","duration":"134.317447ms","start":"2026-05-21T13:43:51.784942Z","end":"2026-05-21T13:43:51.919259Z","steps":["trace[1022699981] 'range keys from in-memory index tree' (duration: 133.872705ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:43:51.919202Z","caller":"traceutil/trace.go:171","msg":"trace[1920468279] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:15157; }","duration":"268.736146ms","start":"2026-05-21T13:43:51.650442Z","end":"2026-05-21T13:43:51.919178Z","steps":["trace[1920468279] 'range keys from in-memory index tree' (duration: 268.367535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:43:51.919339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.288347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-05-21T13:43:51.919505Z","caller":"traceutil/trace.go:171","msg":"trace[610340691] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15157; }","duration":"115.520803ms","start":"2026-05-21T13:43:51.803971Z","end":"2026-05-21T13:43:51.919491Z","steps":["trace[610340691] 'range keys from in-memory index tree' (duration: 115.055191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:43:52.35052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.96084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-7n24j\" ","response":"range_response_count:1 size:20739"} {"level":"info","ts":"2026-05-21T13:43:52.350634Z","caller":"traceutil/trace.go:171","msg":"trace[1509468749] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-7n24j; range_end:; response_count:1; response_revision:15158; }","duration":"103.122484ms","start":"2026-05-21T13:43:52.247492Z","end":"2026-05-21T13:43:52.350614Z","steps":["trace[1509468749] 'range keys from in-memory index tree' (duration: 102.772855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:43:52.351089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.668972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T13:43:52.351108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.752864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T13:43:52.351164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.56582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-05-21T13:43:52.351183Z","caller":"traceutil/trace.go:171","msg":"trace[1069987581] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15158; }","duration":"102.788255ms","start":"2026-05-21T13:43:52.248371Z","end":"2026-05-21T13:43:52.35116Z","steps":["trace[1069987581] 'range keys from in-memory index tree' (duration: 102.59902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:43:52.351193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-05-21T13:43:52.351226Z","caller":"traceutil/trace.go:171","msg":"trace[637498455] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15158; }","duration":"102.914269ms","start":"2026-05-21T13:43:52.24828Z","end":"2026-05-21T13:43:52.351195Z","steps":["trace[637498455] 'range keys from in-memory index tree' (duration: 102.678563ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:43:52.351231Z","caller":"traceutil/trace.go:171","msg":"trace[8158650] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15158; }","duration":"102.685263ms","start":"2026-05-21T13:43:52.248531Z","end":"2026-05-21T13:43:52.351216Z","steps":["trace[8158650] 'range keys from in-memory index tree' (duration: 102.362933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:43:52.351248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.928059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-05-21T13:43:52.351279Z","caller":"traceutil/trace.go:171","msg":"trace[1164428662] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15158; }","duration":"103.086844ms","start":"2026-05-21T13:43:52.248173Z","end":"2026-05-21T13:43:52.35126Z","steps":["trace[1164428662] 'range keys from in-memory index tree' (duration: 102.796875ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:43:52.351318Z","caller":"traceutil/trace.go:171","msg":"trace[1503080297] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15158; }","duration":"103.002151ms","start":"2026-05-21T13:43:52.248299Z","end":"2026-05-21T13:43:52.351301Z","steps":["trace[1503080297] 'range keys from in-memory index tree' (duration: 102.720823ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:44:39.651469Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12878} {"level":"info","ts":"2026-05-21T13:44:39.734301Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12878,"took":"75.057019ms","hash":2921099471,"current-db-size-bytes":57802752,"current-db-size":"58 MB","current-db-size-in-use-bytes":30670848,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-21T13:44:39.734383Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2921099471,"revision":12878,"compact-revision":10802} {"level":"info","ts":"2026-05-21T13:49:39.663136Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15462} {"level":"info","ts":"2026-05-21T13:49:39.753088Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15462,"took":"87.536999ms","hash":1392709931,"current-db-size-bytes":57802752,"current-db-size":"58 MB","current-db-size-in-use-bytes":31821824,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-21T13:49:39.753138Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1392709931,"revision":15462,"compact-revision":12878} {"level":"warn","ts":"2026-05-21T13:49:42.971762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.627704ms","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:6"} {"level":"info","ts":"2026-05-21T13:49:42.971852Z","caller":"traceutil/trace.go:171","msg":"trace[337117429] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:17733; }","duration":"156.760928ms","start":"2026-05-21T13:49:42.815068Z","end":"2026-05-21T13:49:42.971829Z","steps":["trace[337117429] 'count revisions from in-memory index tree' (duration: 156.571613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:49:42.97191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.256719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-7rgxv\" ","response":"range_response_count:1 size:22086"} {"level":"info","ts":"2026-05-21T13:49:42.972041Z","caller":"traceutil/trace.go:171","msg":"trace[1334144273] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-7rgxv; range_end:; response_count:1; response_revision:17733; }","duration":"180.440344ms","start":"2026-05-21T13:49:42.791577Z","end":"2026-05-21T13:49:42.972018Z","steps":["trace[1334144273] 'range keys from in-memory index tree' (duration: 180.036354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:49:44.738517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.509816ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-21T13:49:44.738603Z","caller":"traceutil/trace.go:171","msg":"trace[660795274] linearizableReadLoop","detail":"{readStateIndex:18353; appliedIndex:18352; }","duration":"244.093282ms","start":"2026-05-21T13:49:44.494498Z","end":"2026-05-21T13:49:44.738591Z","steps":["trace[660795274] 'read index received' (duration: 118.430942ms)","trace[660795274] 'applied index is now lower than readState.Index' (duration: 125.66142ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:49:44.738761Z","caller":"traceutil/trace.go:171","msg":"trace[528869656] transaction","detail":"{read_only:false; response_revision:17749; number_of_response:1; }","duration":"258.127365ms","start":"2026-05-21T13:49:44.480623Z","end":"2026-05-21T13:49:44.73875Z","steps":["trace[528869656] 'process raft request' (duration: 132.320401ms)","trace[528869656] 'compare' (duration: 125.329461ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T13:49:44.73903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.528573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-21T13:49:44.739058Z","caller":"traceutil/trace.go:171","msg":"trace[479900255] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17749; }","duration":"244.589245ms","start":"2026-05-21T13:49:44.494461Z","end":"2026-05-21T13:49:44.739051Z","steps":["trace[479900255] 'agreement among raft nodes before linearized reading' (duration: 244.41135ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:49:44.887971Z","caller":"traceutil/trace.go:171","msg":"trace[565982702] linearizableReadLoop","detail":"{readStateIndex:18354; appliedIndex:18353; }","duration":"149.321206ms","start":"2026-05-21T13:49:44.73862Z","end":"2026-05-21T13:49:44.887941Z","steps":["trace[565982702] 'read index received' (duration: 124.584831ms)","trace[565982702] 'applied index is now lower than readState.Index' (duration: 24.735675ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T13:49:44.888098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.343227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-05-21T13:49:44.888122Z","caller":"traceutil/trace.go:171","msg":"trace[1354002021] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:17750; }","duration":"367.410758ms","start":"2026-05-21T13:49:44.520705Z","end":"2026-05-21T13:49:44.888116Z","steps":["trace[1354002021] 'agreement among raft nodes before linearized reading' (duration: 367.340786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:49:44.888158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:49:44.52069Z","time spent":"367.46346ms","remote":"127.0.0.1:46976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":380,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"info","ts":"2026-05-21T13:49:44.88814Z","caller":"traceutil/trace.go:171","msg":"trace[406782113] transaction","detail":"{read_only:false; response_revision:17750; number_of_response:1; }","duration":"258.62854ms","start":"2026-05-21T13:49:44.629477Z","end":"2026-05-21T13:49:44.888106Z","steps":["trace[406782113] 'process raft request' (duration: 233.883164ms)","trace[406782113] 'compare' (duration: 24.483448ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T13:49:44.888172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.424896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-21T13:49:44.888269Z","caller":"traceutil/trace.go:171","msg":"trace[1136789818] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:17750; }","duration":"224.59898ms","start":"2026-05-21T13:49:44.663649Z","end":"2026-05-21T13:49:44.888248Z","steps":["trace[1136789818] 'agreement among raft nodes before linearized reading' (duration: 224.348274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:49:44.888258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.644685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-05-21T13:49:44.888326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.41099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-21T13:49:44.888346Z","caller":"traceutil/trace.go:171","msg":"trace[836896623] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17750; }","duration":"148.431811ms","start":"2026-05-21T13:49:44.73991Z","end":"2026-05-21T13:49:44.888342Z","steps":["trace[836896623] 'agreement among raft nodes before linearized reading' (duration: 148.39954ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:49:44.888341Z","caller":"traceutil/trace.go:171","msg":"trace[1402729926] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:17750; }","duration":"282.772327ms","start":"2026-05-21T13:49:44.605543Z","end":"2026-05-21T13:49:44.888315Z","steps":["trace[1402729926] 'agreement among raft nodes before linearized reading' (duration: 282.486309ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:54:33.492436Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"66f7b57d56ad567d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-21T13:54:33.500561Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-21T13:54:33.500813Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-21T13:54:39.672539Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17716} {"level":"info","ts":"2026-05-21T13:54:39.765454Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17716,"took":"90.343346ms","hash":784494270,"current-db-size-bytes":57802752,"current-db-size":"58 MB","current-db-size-in-use-bytes":27688960,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-21T13:54:39.765516Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":784494270,"revision":17716,"compact-revision":15462}