{"level":"warn","ts":"2026-07-29T00:55:05.848987Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-29T00:55:05.84906Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.222:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.222:2380","--initial-cluster=instance=https://162.253.55.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.222: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-07-29T00:55:05.849116Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-29T00:55:05.849194Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.222:2380"]} {"level":"info","ts":"2026-07-29T00:55:05.849216Z","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-07-29T00:55:05.850368Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.222:2379"]} {"level":"info","ts":"2026-07-29T00:55:05.850442Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.222:2380"],"listen-peer-urls":["https://162.253.55.222:2380"],"advertise-client-urls":["https://162.253.55.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.222: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-07-29T00:55:05.854143Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.523921ms"} {"level":"info","ts":"2026-07-29T00:55:05.859218Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5a9e84e0e0a90b63","cluster-id":"c7ddd50204a2b828"} {"level":"info","ts":"2026-07-29T00:55:05.859271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 switched to configuration voters=()"} {"level":"info","ts":"2026-07-29T00:55:05.85929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 became follower at term 0"} {"level":"info","ts":"2026-07-29T00:55:05.859303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5a9e84e0e0a90b63 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-29T00:55:05.85931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 became follower at term 1"} {"level":"info","ts":"2026-07-29T00:55:05.859342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 switched to configuration voters=(6529802611110513507)"} {"level":"warn","ts":"2026-07-29T00:55:05.862878Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-29T00:55:05.864194Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-29T00:55:05.865409Z","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-07-29T00:55:05.867068Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5a9e84e0e0a90b63","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-29T00:55:05.869927Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5a9e84e0e0a90b63","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-29T00:55:05.870021Z","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-07-29T00:55:05.870092Z","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-07-29T00:55:05.870122Z","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-07-29T00:55:05.870344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 switched to configuration voters=(6529802611110513507)"} {"level":"info","ts":"2026-07-29T00:55:05.870609Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c7ddd50204a2b828","local-member-id":"5a9e84e0e0a90b63","added-peer-id":"5a9e84e0e0a90b63","added-peer-peer-urls":["https://162.253.55.222:2380"]} {"level":"info","ts":"2026-07-29T00:55:05.870853Z","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-07-29T00:55:05.87112Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5a9e84e0e0a90b63","initial-advertise-peer-urls":["https://162.253.55.222:2380"],"listen-peer-urls":["https://162.253.55.222:2380"],"advertise-client-urls":["https://162.253.55.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-29T00:55:05.871179Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-29T00:55:05.871305Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.222:2380"} {"level":"info","ts":"2026-07-29T00:55:05.871329Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.222:2380"} {"level":"info","ts":"2026-07-29T00:55:05.959471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 is starting a new election at term 1"} {"level":"info","ts":"2026-07-29T00:55:05.959508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-29T00:55:05.959538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 received MsgPreVoteResp from 5a9e84e0e0a90b63 at term 1"} {"level":"info","ts":"2026-07-29T00:55:05.959556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 became candidate at term 2"} {"level":"info","ts":"2026-07-29T00:55:05.959567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 received MsgVoteResp from 5a9e84e0e0a90b63 at term 2"} {"level":"info","ts":"2026-07-29T00:55:05.959587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 became leader at term 2"} {"level":"info","ts":"2026-07-29T00:55:05.959607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5a9e84e0e0a90b63 elected leader 5a9e84e0e0a90b63 at term 2"} {"level":"info","ts":"2026-07-29T00:55:05.960547Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-29T00:55:05.961306Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5a9e84e0e0a90b63","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.222:2379]}","request-path":"/0/members/5a9e84e0e0a90b63/attributes","cluster-id":"c7ddd50204a2b828","publish-timeout":"7s"} {"level":"info","ts":"2026-07-29T00:55:05.961317Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-29T00:55:05.961357Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-29T00:55:05.961855Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c7ddd50204a2b828","local-member-id":"5a9e84e0e0a90b63","cluster-version":"3.5"} {"level":"info","ts":"2026-07-29T00:55:05.961796Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-29T00:55:05.96192Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-29T00:55:05.961943Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-29T00:55:05.961974Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-29T00:55:05.964592Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-29T00:55:05.964828Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.222:2379"} {"level":"info","ts":"2026-07-29T00:55:23.688897Z","caller":"traceutil/trace.go:171","msg":"trace[1627962535] linearizableReadLoop","detail":"{readStateIndex:383; appliedIndex:382; }","duration":"150.031288ms","start":"2026-07-29T00:55:23.538841Z","end":"2026-07-29T00:55:23.688872Z","steps":["trace[1627962535] 'read index received' (duration: 149.879655ms)","trace[1627962535] 'applied index is now lower than readState.Index' (duration: 150.553µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T00:55:23.68903Z","caller":"traceutil/trace.go:171","msg":"trace[691318600] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"161.94412ms","start":"2026-07-29T00:55:23.527046Z","end":"2026-07-29T00:55:23.68899Z","steps":["trace[691318600] 'process raft request' (duration: 161.694085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:55:23.68924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.359225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-29T00:55:23.68959Z","caller":"traceutil/trace.go:171","msg":"trace[1905029522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:368; }","duration":"150.753292ms","start":"2026-07-29T00:55:23.538816Z","end":"2026-07-29T00:55:23.689569Z","steps":["trace[1905029522] 'agreement among raft nodes before linearized reading' (duration: 150.251642ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:55:47.936094Z","caller":"traceutil/trace.go:171","msg":"trace[1490668224] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"122.208422ms","start":"2026-07-29T00:55:47.813853Z","end":"2026-07-29T00:55:47.936061Z","steps":["trace[1490668224] 'read index received' (duration: 122.053469ms)","trace[1490668224] 'applied index is now lower than readState.Index' (duration: 153.913µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T00:55:47.936186Z","caller":"traceutil/trace.go:171","msg":"trace[1249863188] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"196.221396ms","start":"2026-07-29T00:55:47.739931Z","end":"2026-07-29T00:55:47.936152Z","steps":["trace[1249863188] 'process raft request' (duration: 195.999981ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:55:47.93642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.628798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-29T00:55:47.936468Z","caller":"traceutil/trace.go:171","msg":"trace[155084615] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:582; }","duration":"116.71152ms","start":"2026-07-29T00:55:47.819744Z","end":"2026-07-29T00:55:47.936456Z","steps":["trace[155084615] 'agreement among raft nodes before linearized reading' (duration: 116.480295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:55:47.936615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.732732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-29T00:55:47.936709Z","caller":"traceutil/trace.go:171","msg":"trace[1206653701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:582; }","duration":"122.878425ms","start":"2026-07-29T00:55:47.81381Z","end":"2026-07-29T00:55:47.936689Z","steps":["trace[1206653701] 'agreement among raft nodes before linearized reading' (duration: 122.355415ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:55:48.087802Z","caller":"traceutil/trace.go:171","msg":"trace[474520924] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:607; }","duration":"139.643356ms","start":"2026-07-29T00:55:47.948141Z","end":"2026-07-29T00:55:48.087784Z","steps":["trace[474520924] 'read index received' (duration: 56.469178ms)","trace[474520924] 'applied index is now lower than readState.Index' (duration: 83.173258ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T00:55:48.087841Z","caller":"traceutil/trace.go:171","msg":"trace[1830171279] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"147.024456ms","start":"2026-07-29T00:55:47.94079Z","end":"2026-07-29T00:55:48.087815Z","steps":["trace[1830171279] 'process raft request' (duration: 111.407054ms)","trace[1830171279] 'compare' (duration: 35.432198ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T00:55:48.087912Z","caller":"traceutil/trace.go:171","msg":"trace[1627410026] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"138.84068ms","start":"2026-07-29T00:55:47.94906Z","end":"2026-07-29T00:55:48.087901Z","steps":["trace[1627410026] 'process raft request' (duration: 138.669906ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:55:48.088065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.911342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T00:55:48.088123Z","caller":"traceutil/trace.go:171","msg":"trace[1906565341] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:585; }","duration":"140.006594ms","start":"2026-07-29T00:55:47.9481Z","end":"2026-07-29T00:55:48.088107Z","steps":["trace[1906565341] 'agreement among raft nodes before linearized reading' (duration: 139.885221ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:55:48.438405Z","caller":"traceutil/trace.go:171","msg":"trace[568553863] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"162.407588ms","start":"2026-07-29T00:55:48.275971Z","end":"2026-07-29T00:55:48.438379Z","steps":["trace[568553863] 'read index received' (duration: 139.114625ms)","trace[568553863] 'applied index is now lower than readState.Index' (duration: 23.291863ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T00:55:48.438484Z","caller":"traceutil/trace.go:171","msg":"trace[803180613] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"346.06264ms","start":"2026-07-29T00:55:48.092404Z","end":"2026-07-29T00:55:48.438467Z","steps":["trace[803180613] 'process raft request' (duration: 322.762256ms)","trace[803180613] 'compare' (duration: 23.099669ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T00:55:48.438577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.591532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T00:55:48.438613Z","caller":"traceutil/trace.go:171","msg":"trace[1377857863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"162.673814ms","start":"2026-07-29T00:55:48.275928Z","end":"2026-07-29T00:55:48.438602Z","steps":["trace[1377857863] 'agreement among raft nodes before linearized reading' (duration: 162.544382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:55:48.439084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:55:48.09238Z","time spent":"346.193243ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T00:55:48.599735Z","caller":"traceutil/trace.go:171","msg":"trace[9755193] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"122.790044ms","start":"2026-07-29T00:55:48.476912Z","end":"2026-07-29T00:55:48.599702Z","steps":["trace[9755193] 'process raft request' (duration: 118.327863ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:56:34.679516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.138501ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-29T00:57:21.128595Z","caller":"traceutil/trace.go:171","msg":"trace[1086904846] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"221.654252ms","start":"2026-07-29T00:57:20.90691Z","end":"2026-07-29T00:57:21.128565Z","steps":["trace[1086904846] 'process raft request' (duration: 221.482928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:57:21.313244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.95285ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-29T00:57:21.313527Z","caller":"traceutil/trace.go:171","msg":"trace[1378042052] linearizableReadLoop","detail":"{readStateIndex:1897; appliedIndex:1896; }","duration":"159.619656ms","start":"2026-07-29T00:57:21.153871Z","end":"2026-07-29T00:57:21.313491Z","steps":["trace[1378042052] 'read index received' (duration: 56.242077ms)","trace[1378042052] 'applied index is now lower than readState.Index' (duration: 103.374099ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T00:57:21.313544Z","caller":"traceutil/trace.go:171","msg":"trace[1032921973] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"400.252786ms","start":"2026-07-29T00:57:20.913226Z","end":"2026-07-29T00:57:21.313479Z","steps":["trace[1032921973] 'process raft request' (duration: 296.974529ms)","trace[1032921973] 'compare' (duration: 102.813678ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T00:57:21.313763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.882851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-29T00:57:21.31382Z","caller":"traceutil/trace.go:171","msg":"trace[814035919] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1827; }","duration":"159.989924ms","start":"2026-07-29T00:57:21.153817Z","end":"2026-07-29T00:57:21.313807Z","steps":["trace[814035919] 'agreement among raft nodes before linearized reading' (duration: 159.82397ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:57:21.314206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T00:57:20.9132Z","time spent":"400.883878ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T00:57:21.648287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.321289ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-29T00:57:21.648619Z","caller":"traceutil/trace.go:171","msg":"trace[1931560500] linearizableReadLoop","detail":"{readStateIndex:1901; appliedIndex:1898; }","duration":"232.746168ms","start":"2026-07-29T00:57:21.415859Z","end":"2026-07-29T00:57:21.648605Z","steps":["trace[1931560500] 'read index received' (duration: 49.145352ms)","trace[1931560500] 'applied index is now lower than readState.Index' (duration: 183.600006ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T00:57:21.648626Z","caller":"traceutil/trace.go:171","msg":"trace[1958324280] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"279.258088ms","start":"2026-07-29T00:57:21.36934Z","end":"2026-07-29T00:57:21.648598Z","steps":["trace[1958324280] 'process raft request' (duration: 95.54518ms)","trace[1958324280] 'compare' (duration: 183.244307ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T00:57:21.648682Z","caller":"traceutil/trace.go:171","msg":"trace[1747618670] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"246.841716ms","start":"2026-07-29T00:57:21.40182Z","end":"2026-07-29T00:57:21.648661Z","steps":["trace[1747618670] 'process raft request' (duration: 246.754784ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:57:21.648711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.84767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T00:57:21.648723Z","caller":"traceutil/trace.go:171","msg":"trace[841622953] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"274.382869ms","start":"2026-07-29T00:57:21.374306Z","end":"2026-07-29T00:57:21.648689Z","steps":["trace[841622953] 'process raft request' (duration: 274.075182ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:57:21.648791Z","caller":"traceutil/trace.go:171","msg":"trace[351542597] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1830; }","duration":"232.966212ms","start":"2026-07-29T00:57:21.415811Z","end":"2026-07-29T00:57:21.648778Z","steps":["trace[351542597] 'agreement among raft nodes before linearized reading' (duration: 232.85606ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:57:21.658202Z","caller":"traceutil/trace.go:171","msg":"trace[707959901] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"185.197738ms","start":"2026-07-29T00:57:21.472979Z","end":"2026-07-29T00:57:21.658176Z","steps":["trace[707959901] 'process raft request' (duration: 185.000084ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:57:36.003932Z","caller":"traceutil/trace.go:171","msg":"trace[82420103] transaction","detail":"{read_only:false; response_revision:1939; number_of_response:1; }","duration":"207.719517ms","start":"2026-07-29T00:57:35.796173Z","end":"2026-07-29T00:57:36.003893Z","steps":["trace[82420103] 'process raft request' (duration: 111.072266ms)","trace[82420103] 'compare' (duration: 96.403276ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T00:58:12.924016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.024513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-29T00:58:12.923906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.39382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-29T00:58:12.924394Z","caller":"traceutil/trace.go:171","msg":"trace[2118532304] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2156; }","duration":"181.835929ms","start":"2026-07-29T00:58:12.742449Z","end":"2026-07-29T00:58:12.924285Z","steps":["trace[2118532304] 'range keys from in-memory index tree' (duration: 181.221577ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:58:12.924253Z","caller":"traceutil/trace.go:171","msg":"trace[1393267419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2156; }","duration":"181.269057ms","start":"2026-07-29T00:58:12.742923Z","end":"2026-07-29T00:58:12.924192Z","steps":["trace[1393267419] 'range keys from in-memory index tree' (duration: 180.686186ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:58:13.53423Z","caller":"traceutil/trace.go:171","msg":"trace[497444233] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"136.848412ms","start":"2026-07-29T00:58:13.397353Z","end":"2026-07-29T00:58:13.534201Z","steps":["trace[497444233] 'process raft request' (duration: 136.699149ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:58:13.68706Z","caller":"traceutil/trace.go:171","msg":"trace[1272192346] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"206.549345ms","start":"2026-07-29T00:58:13.480475Z","end":"2026-07-29T00:58:13.687024Z","steps":["trace[1272192346] 'process raft request' (duration: 176.098353ms)","trace[1272192346] 'compare' (duration: 30.253028ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T00:58:13.687343Z","caller":"traceutil/trace.go:171","msg":"trace[781162519] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"204.009252ms","start":"2026-07-29T00:58:13.483288Z","end":"2026-07-29T00:58:13.687297Z","steps":["trace[781162519] 'process raft request' (duration: 203.663785ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:58:13.949937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.108198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-29T00:58:13.950035Z","caller":"traceutil/trace.go:171","msg":"trace[558543171] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:2161; }","duration":"162.325442ms","start":"2026-07-29T00:58:13.787687Z","end":"2026-07-29T00:58:13.950012Z","steps":["trace[558543171] 'count revisions from in-memory index tree' (duration: 162.010985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:59:33.317568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.399589ms","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-07-29T00:59:33.317721Z","caller":"traceutil/trace.go:171","msg":"trace[884771657] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2784; }","duration":"103.563792ms","start":"2026-07-29T00:59:33.214138Z","end":"2026-07-29T00:59:33.317702Z","steps":["trace[884771657] 'range keys from in-memory index tree' (duration: 103.372018ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T00:59:33.318087Z","caller":"traceutil/trace.go:171","msg":"trace[629267440] transaction","detail":"{read_only:false; response_revision:2785; number_of_response:1; }","duration":"139.418356ms","start":"2026-07-29T00:59:33.1786Z","end":"2026-07-29T00:59:33.318019Z","steps":["trace[629267440] 'process raft request' (duration: 131.542811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T00:59:33.616791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.139723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-29T00:59:33.616897Z","caller":"traceutil/trace.go:171","msg":"trace[513828352] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:2786; }","duration":"106.309946ms","start":"2026-07-29T00:59:33.510565Z","end":"2026-07-29T00:59:33.616875Z","steps":["trace[513828352] 'count revisions from in-memory index tree' (duration: 105.949149ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:03:20.283005Z","caller":"traceutil/trace.go:171","msg":"trace[983766740] linearizableReadLoop","detail":"{readStateIndex:5043; appliedIndex:5040; }","duration":"102.929933ms","start":"2026-07-29T01:03:20.18005Z","end":"2026-07-29T01:03:20.28298Z","steps":["trace[983766740] 'read index received' (duration: 3.350808ms)","trace[983766740] 'applied index is now lower than readState.Index' (duration: 99.578055ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T01:03:20.283097Z","caller":"traceutil/trace.go:171","msg":"trace[1306333442] transaction","detail":"{read_only:false; response_revision:4860; number_of_response:1; }","duration":"104.517064ms","start":"2026-07-29T01:03:20.178548Z","end":"2026-07-29T01:03:20.283065Z","steps":["trace[1306333442] 'process raft request' (duration: 40.253158ms)","trace[1306333442] 'compare' (duration: 63.994921ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T01:03:20.283314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.235479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0265a775-fe2c-4b43-97e0-c4fc569ccda4\" ","response":"range_response_count:1 size:2535"} {"level":"info","ts":"2026-07-29T01:03:20.283398Z","caller":"traceutil/trace.go:171","msg":"trace[388110999] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0265a775-fe2c-4b43-97e0-c4fc569ccda4; range_end:; response_count:1; response_revision:4860; }","duration":"103.371022ms","start":"2026-07-29T01:03:20.180008Z","end":"2026-07-29T01:03:20.283379Z","steps":["trace[388110999] 'agreement among raft nodes before linearized reading' (duration: 103.128327ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:03:30.426159Z","caller":"traceutil/trace.go:171","msg":"trace[359494480] transaction","detail":"{read_only:false; response_revision:5101; number_of_response:1; }","duration":"116.533169ms","start":"2026-07-29T01:03:30.309597Z","end":"2026-07-29T01:03:30.42613Z","steps":["trace[359494480] 'process raft request' (duration: 85.620111ms)","trace[359494480] 'compare' (duration: 30.763335ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T01:03:33.50918Z","caller":"traceutil/trace.go:171","msg":"trace[1636681426] linearizableReadLoop","detail":"{readStateIndex:5323; appliedIndex:5322; }","duration":"137.861313ms","start":"2026-07-29T01:03:33.371296Z","end":"2026-07-29T01:03:33.509157Z","steps":["trace[1636681426] 'read index received' (duration: 137.650708ms)","trace[1636681426] 'applied index is now lower than readState.Index' (duration: 209.785µs)"],"step_count":2} {"level":"warn","ts":"2026-07-29T01:03:33.509631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.318742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-07-29T01:03:33.509695Z","caller":"traceutil/trace.go:171","msg":"trace[1523900787] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5132; }","duration":"138.396683ms","start":"2026-07-29T01:03:33.371281Z","end":"2026-07-29T01:03:33.509678Z","steps":["trace[1523900787] 'agreement among raft nodes before linearized reading' (duration: 138.205599ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:03:33.510115Z","caller":"traceutil/trace.go:171","msg":"trace[965908984] transaction","detail":"{read_only:false; response_revision:5132; number_of_response:1; }","duration":"202.544668ms","start":"2026-07-29T01:03:33.307556Z","end":"2026-07-29T01:03:33.5101Z","steps":["trace[965908984] 'process raft request' (duration: 201.454285ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T01:03:33.510363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.989008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T01:03:33.510461Z","caller":"traceutil/trace.go:171","msg":"trace[558570001] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:5132; }","duration":"117.141511ms","start":"2026-07-29T01:03:33.393302Z","end":"2026-07-29T01:03:33.510443Z","steps":["trace[558570001] 'agreement among raft nodes before linearized reading' (duration: 116.955598ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:03:43.243154Z","caller":"traceutil/trace.go:171","msg":"trace[1353867794] linearizableReadLoop","detail":"{readStateIndex:5564; appliedIndex:5561; }","duration":"151.445268ms","start":"2026-07-29T01:03:43.091686Z","end":"2026-07-29T01:03:43.243131Z","steps":["trace[1353867794] 'read index received' (duration: 735.005µs)","trace[1353867794] 'applied index is now lower than readState.Index' (duration: 150.709023ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T01:03:43.24325Z","caller":"traceutil/trace.go:171","msg":"trace[833173286] transaction","detail":"{read_only:false; response_revision:5368; number_of_response:1; }","duration":"158.489752ms","start":"2026-07-29T01:03:43.084736Z","end":"2026-07-29T01:03:43.243226Z","steps":["trace[833173286] 'process raft request' (duration: 158.298309ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:03:43.243291Z","caller":"traceutil/trace.go:171","msg":"trace[514398261] transaction","detail":"{read_only:false; response_revision:5367; number_of_response:1; }","duration":"161.568274ms","start":"2026-07-29T01:03:43.081695Z","end":"2026-07-29T01:03:43.243264Z","steps":["trace[514398261] 'process raft request' (duration: 142.879134ms)","trace[514398261] 'compare' (duration: 18.250421ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T01:03:43.243547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.842806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-07-29T01:03:43.24367Z","caller":"traceutil/trace.go:171","msg":"trace[2050498882] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5368; }","duration":"151.994979ms","start":"2026-07-29T01:03:43.091657Z","end":"2026-07-29T01:03:43.243652Z","steps":["trace[2050498882] 'agreement among raft nodes before linearized reading' (duration: 151.743904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T01:03:43.243514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.231405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7282823f-6576-4643-9f7c-eaca80b7ed60\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-29T01:03:43.243777Z","caller":"traceutil/trace.go:171","msg":"trace[1256122458] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7282823f-6576-4643-9f7c-eaca80b7ed60; range_end:; response_count:1; response_revision:5368; }","duration":"124.521461ms","start":"2026-07-29T01:03:43.119224Z","end":"2026-07-29T01:03:43.243745Z","steps":["trace[1256122458] 'agreement among raft nodes before linearized reading' (duration: 124.194224ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:03:43.613458Z","caller":"traceutil/trace.go:171","msg":"trace[1891673127] transaction","detail":"{read_only:false; response_revision:5369; number_of_response:1; }","duration":"309.147844ms","start":"2026-07-29T01:03:43.304285Z","end":"2026-07-29T01:03:43.613433Z","steps":["trace[1891673127] 'process raft request' (duration: 308.978961ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:03:43.613473Z","caller":"traceutil/trace.go:171","msg":"trace[1962578575] linearizableReadLoop","detail":"{readStateIndex:5565; appliedIndex:5565; }","duration":"198.439424ms","start":"2026-07-29T01:03:43.415003Z","end":"2026-07-29T01:03:43.613442Z","steps":["trace[1962578575] 'read index received' (duration: 198.429114ms)","trace[1962578575] 'applied index is now lower than readState.Index' (duration: 8.19µs)"],"step_count":2} {"level":"warn","ts":"2026-07-29T01:03:43.613619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T01:03:43.304272Z","time spent":"309.264256ms","remote":"127.0.0.1:47158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7599,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T01:03:43.613751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.72371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7282823f-6576-4643-9f7c-eaca80b7ed60\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-29T01:03:43.613797Z","caller":"traceutil/trace.go:171","msg":"trace[620573644] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7282823f-6576-4643-9f7c-eaca80b7ed60; range_end:; response_count:1; response_revision:5369; }","duration":"198.807892ms","start":"2026-07-29T01:03:43.414978Z","end":"2026-07-29T01:03:43.613785Z","steps":["trace[620573644] 'agreement among raft nodes before linearized reading' (duration: 198.598268ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:05:06.25818Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2967} {"level":"info","ts":"2026-07-29T01:05:06.330676Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2967,"took":"70.783511ms","hash":2325672759,"current-db-size-bytes":59654144,"current-db-size":"60 MB","current-db-size-in-use-bytes":34500608,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-29T01:05:06.330753Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2325672759,"revision":2967,"compact-revision":-1} {"level":"info","ts":"2026-07-29T01:09:53.816996Z","caller":"traceutil/trace.go:171","msg":"trace[1110170988] transaction","detail":"{read_only:false; response_revision:8196; number_of_response:1; }","duration":"166.204101ms","start":"2026-07-29T01:09:53.650721Z","end":"2026-07-29T01:09:53.816925Z","steps":["trace[1110170988] 'process raft request' (duration: 165.374544ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:10:06.405362Z","caller":"traceutil/trace.go:171","msg":"trace[997738464] transaction","detail":"{read_only:false; response_revision:8259; number_of_response:1; }","duration":"230.080336ms","start":"2026-07-29T01:10:06.175253Z","end":"2026-07-29T01:10:06.405333Z","steps":["trace[997738464] 'process raft request' (duration: 229.821561ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:10:06.469539Z","caller":"traceutil/trace.go:171","msg":"trace[1609778150] transaction","detail":"{read_only:false; response_revision:8260; number_of_response:1; }","duration":"292.34677ms","start":"2026-07-29T01:10:06.177159Z","end":"2026-07-29T01:10:06.469506Z","steps":["trace[1609778150] 'process raft request' (duration: 275.330195ms)","trace[1609778150] 'compare' (duration: 16.831241ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T01:10:06.469635Z","caller":"traceutil/trace.go:171","msg":"trace[130577707] linearizableReadLoop","detail":"{readStateIndex:8547; appliedIndex:8545; }","duration":"273.787852ms","start":"2026-07-29T01:10:06.195836Z","end":"2026-07-29T01:10:06.469624Z","steps":["trace[130577707] 'read index received' (duration: 209.276614ms)","trace[130577707] 'applied index is now lower than readState.Index' (duration: 64.510278ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T01:10:06.469543Z","caller":"traceutil/trace.go:171","msg":"trace[1962891867] transaction","detail":"{read_only:false; response_revision:8261; number_of_response:1; }","duration":"209.270404ms","start":"2026-07-29T01:10:06.260248Z","end":"2026-07-29T01:10:06.469519Z","steps":["trace[1962891867] 'process raft request' (duration: 209.180122ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:10:06.469742Z","caller":"traceutil/trace.go:171","msg":"trace[2083662224] transaction","detail":"{read_only:false; response_revision:8262; number_of_response:1; }","duration":"112.402209ms","start":"2026-07-29T01:10:06.357312Z","end":"2026-07-29T01:10:06.469714Z","steps":["trace[2083662224] 'process raft request' (duration: 112.155514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T01:10:06.469853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.990287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-6987fb65bc-67trd.18c69d6b3bf4a195\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-07-29T01:10:06.469892Z","caller":"traceutil/trace.go:171","msg":"trace[170520141] range","detail":"{range_begin:/registry/events/openstack/barbican-api-6987fb65bc-67trd.18c69d6b3bf4a195; range_end:; response_count:1; response_revision:8262; }","duration":"274.073618ms","start":"2026-07-29T01:10:06.195805Z","end":"2026-07-29T01:10:06.469879Z","steps":["trace[170520141] 'agreement among raft nodes before linearized reading' (duration: 273.915325ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T01:10:06.469964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.917882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T01:10:06.470056Z","caller":"traceutil/trace.go:171","msg":"trace[2043584620] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8262; }","duration":"194.047285ms","start":"2026-07-29T01:10:06.275985Z","end":"2026-07-29T01:10:06.470032Z","steps":["trace[2043584620] 'agreement among raft nodes before linearized reading' (duration: 193.877842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T01:10:06.469975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.050132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T01:10:06.470119Z","caller":"traceutil/trace.go:171","msg":"trace[104606778] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:8262; }","duration":"109.232686ms","start":"2026-07-29T01:10:06.360866Z","end":"2026-07-29T01:10:06.470098Z","steps":["trace[104606778] 'agreement among raft nodes before linearized reading' (duration: 109.020011ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:10:06.525344Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6097} {"level":"info","ts":"2026-07-29T01:10:06.760625Z","caller":"traceutil/trace.go:171","msg":"trace[1612385334] transaction","detail":"{read_only:false; response_revision:8267; number_of_response:1; }","duration":"157.075155ms","start":"2026-07-29T01:10:06.603527Z","end":"2026-07-29T01:10:06.760603Z","steps":["trace[1612385334] 'process raft request' (duration: 133.825053ms)","trace[1612385334] 'compare' (duration: 23.092649ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T01:10:06.760853Z","caller":"traceutil/trace.go:171","msg":"trace[237417433] transaction","detail":"{read_only:false; response_revision:8268; number_of_response:1; }","duration":"151.38465ms","start":"2026-07-29T01:10:06.609411Z","end":"2026-07-29T01:10:06.760795Z","steps":["trace[237417433] 'process raft request' (duration: 151.149955ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:10:07.01268Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6097,"took":"485.069887ms","hash":1135108477,"current-db-size-bytes":59654144,"current-db-size":"60 MB","current-db-size-in-use-bytes":23347200,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-07-29T01:10:07.012764Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1135108477,"revision":6097,"compact-revision":2967} {"level":"info","ts":"2026-07-29T01:10:34.269052Z","caller":"traceutil/trace.go:171","msg":"trace[1118417574] transaction","detail":"{read_only:false; response_revision:8466; number_of_response:1; }","duration":"236.569073ms","start":"2026-07-29T01:10:34.032431Z","end":"2026-07-29T01:10:34.269Z","steps":["trace[1118417574] 'process raft request' (duration: 236.386859ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:10:38.394041Z","caller":"traceutil/trace.go:171","msg":"trace[1202209234] transaction","detail":"{read_only:false; response_revision:8488; number_of_response:1; }","duration":"105.093525ms","start":"2026-07-29T01:10:38.288923Z","end":"2026-07-29T01:10:38.394017Z","steps":["trace[1202209234] 'process raft request' (duration: 104.960532ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:10:46.165386Z","caller":"traceutil/trace.go:171","msg":"trace[526634539] transaction","detail":"{read_only:false; response_revision:8570; number_of_response:1; }","duration":"133.61333ms","start":"2026-07-29T01:10:46.031741Z","end":"2026-07-29T01:10:46.165354Z","steps":["trace[526634539] 'process raft request' (duration: 70.72232ms)","trace[526634539] 'compare' (duration: 62.735347ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T01:10:46.443253Z","caller":"traceutil/trace.go:171","msg":"trace[1120282148] linearizableReadLoop","detail":"{readStateIndex:8871; appliedIndex:8870; }","duration":"168.790042ms","start":"2026-07-29T01:10:46.274437Z","end":"2026-07-29T01:10:46.443227Z","steps":["trace[1120282148] 'read index received' (duration: 168.627499ms)","trace[1120282148] 'applied index is now lower than readState.Index' (duration: 161.503µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T01:10:46.443379Z","caller":"traceutil/trace.go:171","msg":"trace[206551891] transaction","detail":"{read_only:false; response_revision:8571; number_of_response:1; }","duration":"246.220989ms","start":"2026-07-29T01:10:46.197147Z","end":"2026-07-29T01:10:46.443368Z","steps":["trace[206551891] 'process raft request' (duration: 245.935113ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T01:10:46.443715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.271582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T01:10:46.443787Z","caller":"traceutil/trace.go:171","msg":"trace[1396585968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8571; }","duration":"169.380794ms","start":"2026-07-29T01:10:46.27439Z","end":"2026-07-29T01:10:46.443771Z","steps":["trace[1396585968] 'agreement among raft nodes before linearized reading' (duration: 169.281882ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:10:47.039243Z","caller":"traceutil/trace.go:171","msg":"trace[496597329] transaction","detail":"{read_only:false; response_revision:8580; number_of_response:1; }","duration":"133.05481ms","start":"2026-07-29T01:10:46.906162Z","end":"2026-07-29T01:10:47.039217Z","steps":["trace[496597329] 'process raft request' (duration: 99.515451ms)","trace[496597329] 'compare' (duration: 33.359705ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T01:10:47.039394Z","caller":"traceutil/trace.go:171","msg":"trace[1384356454] transaction","detail":"{read_only:false; response_revision:8581; number_of_response:1; }","duration":"126.301173ms","start":"2026-07-29T01:10:46.913064Z","end":"2026-07-29T01:10:47.039365Z","steps":["trace[1384356454] 'process raft request' (duration: 126.101759ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:10:47.37876Z","caller":"traceutil/trace.go:171","msg":"trace[931147787] transaction","detail":"{read_only:false; response_revision:8582; number_of_response:1; }","duration":"196.683716ms","start":"2026-07-29T01:10:47.182055Z","end":"2026-07-29T01:10:47.378739Z","steps":["trace[931147787] 'process raft request' (duration: 196.502442ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:10:47.378759Z","caller":"traceutil/trace.go:171","msg":"trace[163777996] linearizableReadLoop","detail":"{readStateIndex:8882; appliedIndex:8881; }","duration":"119.079116ms","start":"2026-07-29T01:10:47.259632Z","end":"2026-07-29T01:10:47.378711Z","steps":["trace[163777996] 'read index received' (duration: 118.940604ms)","trace[163777996] 'applied index is now lower than readState.Index' (duration: 137.642µs)"],"step_count":2} {"level":"warn","ts":"2026-07-29T01:10:47.378933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.2794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xxhj4\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-07-29T01:10:47.378987Z","caller":"traceutil/trace.go:171","msg":"trace[603066687] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xxhj4; range_end:; response_count:1; response_revision:8582; }","duration":"119.385063ms","start":"2026-07-29T01:10:47.259587Z","end":"2026-07-29T01:10:47.378972Z","steps":["trace[603066687] 'agreement among raft nodes before linearized reading' (duration: 119.220279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T01:10:47.397056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.736512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T01:10:47.397111Z","caller":"traceutil/trace.go:171","msg":"trace[1443225435] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8583; }","duration":"120.848164ms","start":"2026-07-29T01:10:47.276249Z","end":"2026-07-29T01:10:47.397097Z","steps":["trace[1443225435] 'agreement among raft nodes before linearized reading' (duration: 120.758222ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:11:02.622063Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000233e.wal"} {"level":"info","ts":"2026-07-29T01:12:51.475147Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5a9e84e0e0a90b63","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-29T01:12:51.48149Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-29T01:12:51.481662Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-29T01:15:06.533371Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8261} {"level":"info","ts":"2026-07-29T01:15:06.613883Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8261,"took":"78.142681ms","hash":3236203579,"current-db-size-bytes":59654144,"current-db-size":"60 MB","current-db-size-in-use-bytes":25530368,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-29T01:15:06.61395Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3236203579,"revision":8261,"compact-revision":6097} {"level":"info","ts":"2026-07-29T01:15:47.817636Z","caller":"traceutil/trace.go:171","msg":"trace[1047568881] transaction","detail":"{read_only:false; response_revision:11179; number_of_response:1; }","duration":"115.549423ms","start":"2026-07-29T01:15:47.702062Z","end":"2026-07-29T01:15:47.817611Z","steps":["trace[1047568881] 'process raft request' (duration: 64.125946ms)","trace[1047568881] 'compare' (duration: 51.313445ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T01:15:48.101233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.802649ms","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-07-29T01:15:48.101441Z","caller":"traceutil/trace.go:171","msg":"trace[1470887029] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11181; }","duration":"115.959202ms","start":"2026-07-29T01:15:47.985353Z","end":"2026-07-29T01:15:48.101312Z","steps":["trace[1470887029] 'range keys from in-memory index tree' (duration: 115.722167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T01:15:48.101442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.717707ms","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-07-29T01:15:48.101542Z","caller":"traceutil/trace.go:171","msg":"trace[1413051792] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11181; }","duration":"116.88327ms","start":"2026-07-29T01:15:47.984633Z","end":"2026-07-29T01:15:48.101516Z","steps":["trace[1413051792] 'range keys from in-memory index tree' (duration: 116.609865ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T01:15:48.101775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.450192ms","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-07-29T01:15:48.101858Z","caller":"traceutil/trace.go:171","msg":"trace[1074845123] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11181; }","duration":"116.562364ms","start":"2026-07-29T01:15:47.985276Z","end":"2026-07-29T01:15:48.101838Z","steps":["trace[1074845123] 'range keys from in-memory index tree' (duration: 116.3916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T01:15:48.101657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.238093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"warn","ts":"2026-07-29T01:15:48.101879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.795609ms","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-07-29T01:15:48.101943Z","caller":"traceutil/trace.go:171","msg":"trace[349644941] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11181; }","duration":"117.424531ms","start":"2026-07-29T01:15:47.984504Z","end":"2026-07-29T01:15:48.101928Z","steps":["trace[349644941] 'range keys from in-memory index tree' (duration: 116.668116ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:15:48.101934Z","caller":"traceutil/trace.go:171","msg":"trace[459541445] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11181; }","duration":"106.53462ms","start":"2026-07-29T01:15:47.99537Z","end":"2026-07-29T01:15:48.101905Z","steps":["trace[459541445] 'range keys from in-memory index tree' (duration: 106.066751ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:20:06.541818Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10757} {"level":"info","ts":"2026-07-29T01:20:06.622668Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10757,"took":"78.534219ms","hash":1165859830,"current-db-size-bytes":59654144,"current-db-size":"60 MB","current-db-size-in-use-bytes":26689536,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-29T01:20:06.622839Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1165859830,"revision":10757,"compact-revision":8261} {"level":"warn","ts":"2026-07-29T01:22:26.35941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.269361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-29T01:22:26.35951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.799668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-29T01:22:26.35957Z","caller":"traceutil/trace.go:171","msg":"trace[2048793014] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13845; }","duration":"127.472855ms","start":"2026-07-29T01:22:26.232079Z","end":"2026-07-29T01:22:26.359552Z","steps":["trace[2048793014] 'range keys from in-memory index tree' (duration: 127.154169ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:22:26.359628Z","caller":"traceutil/trace.go:171","msg":"trace[1984841680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13845; }","duration":"117.955701ms","start":"2026-07-29T01:22:26.24165Z","end":"2026-07-29T01:22:26.359606Z","steps":["trace[1984841680] 'range keys from in-memory index tree' (duration: 117.651045ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:22:27.332906Z","caller":"traceutil/trace.go:171","msg":"trace[2031315973] linearizableReadLoop","detail":"{readStateIndex:14323; appliedIndex:14322; }","duration":"153.71775ms","start":"2026-07-29T01:22:27.179161Z","end":"2026-07-29T01:22:27.332879Z","steps":["trace[2031315973] 'read index received' (duration: 153.551156ms)","trace[2031315973] 'applied index is now lower than readState.Index' (duration: 165.444µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T01:22:27.333018Z","caller":"traceutil/trace.go:171","msg":"trace[2308849] transaction","detail":"{read_only:false; response_revision:13851; number_of_response:1; }","duration":"209.463815ms","start":"2026-07-29T01:22:27.123511Z","end":"2026-07-29T01:22:27.332975Z","steps":["trace[2308849] 'process raft request' (duration: 209.21985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T01:22:27.33311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.931124ms","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-07-29T01:22:27.333196Z","caller":"traceutil/trace.go:171","msg":"trace[1687847979] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:13851; }","duration":"154.083467ms","start":"2026-07-29T01:22:27.179102Z","end":"2026-07-29T01:22:27.333186Z","steps":["trace[1687847979] 'agreement among raft nodes before linearized reading' (duration: 153.940164ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:22:36.632145Z","caller":"traceutil/trace.go:171","msg":"trace[1097062488] transaction","detail":"{read_only:false; response_revision:14014; number_of_response:1; }","duration":"173.667656ms","start":"2026-07-29T01:22:36.458454Z","end":"2026-07-29T01:22:36.632121Z","steps":["trace[1097062488] 'process raft request' (duration: 125.756841ms)","trace[1097062488] 'compare' (duration: 47.683881ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T01:22:36.637511Z","caller":"traceutil/trace.go:171","msg":"trace[1701059965] transaction","detail":"{read_only:false; response_revision:14016; number_of_response:1; }","duration":"176.78178ms","start":"2026-07-29T01:22:36.460708Z","end":"2026-07-29T01:22:36.63749Z","steps":["trace[1701059965] 'process raft request' (duration: 176.742729ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:22:36.637793Z","caller":"traceutil/trace.go:171","msg":"trace[638421572] transaction","detail":"{read_only:false; response_revision:14015; number_of_response:1; }","duration":"177.751448ms","start":"2026-07-29T01:22:36.460032Z","end":"2026-07-29T01:22:36.637784Z","steps":["trace[638421572] 'process raft request' (duration: 177.31292ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:22:36.918962Z","caller":"traceutil/trace.go:171","msg":"trace[1483453660] linearizableReadLoop","detail":"{readStateIndex:14492; appliedIndex:14491; }","duration":"131.906536ms","start":"2026-07-29T01:22:36.787025Z","end":"2026-07-29T01:22:36.918932Z","steps":["trace[1483453660] 'read index received' (duration: 131.703662ms)","trace[1483453660] 'applied index is now lower than readState.Index' (duration: 201.494µs)"],"step_count":2} {"level":"warn","ts":"2026-07-29T01:22:36.91915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.101829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-29T01:22:36.919215Z","caller":"traceutil/trace.go:171","msg":"trace[1461297891] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:14017; }","duration":"132.191081ms","start":"2026-07-29T01:22:36.78701Z","end":"2026-07-29T01:22:36.919201Z","steps":["trace[1461297891] 'agreement among raft nodes before linearized reading' (duration: 132.071649ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:22:36.919071Z","caller":"traceutil/trace.go:171","msg":"trace[207041578] transaction","detail":"{read_only:false; response_revision:14017; number_of_response:1; }","duration":"240.445886ms","start":"2026-07-29T01:22:36.678595Z","end":"2026-07-29T01:22:36.919041Z","steps":["trace[207041578] 'process raft request' (duration: 240.16784ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T01:22:36.919861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.080117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-29T01:22:36.919936Z","caller":"traceutil/trace.go:171","msg":"trace[2076176831] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:14017; }","duration":"126.20135ms","start":"2026-07-29T01:22:36.793717Z","end":"2026-07-29T01:22:36.919918Z","steps":["trace[2076176831] 'agreement among raft nodes before linearized reading' (duration: 125.460505ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:23:40.860741Z","caller":"traceutil/trace.go:171","msg":"trace[980763879] transaction","detail":"{read_only:false; response_revision:14913; number_of_response:1; }","duration":"114.032623ms","start":"2026-07-29T01:23:40.746663Z","end":"2026-07-29T01:23:40.860695Z","steps":["trace[980763879] 'process raft request' (duration: 113.800039ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:24:10.084317Z","caller":"traceutil/trace.go:171","msg":"trace[475614765] transaction","detail":"{read_only:false; response_revision:15074; number_of_response:1; }","duration":"126.973686ms","start":"2026-07-29T01:24:09.957306Z","end":"2026-07-29T01:24:10.084279Z","steps":["trace[475614765] 'process raft request' (duration: 79.322095ms)","trace[475614765] 'compare' (duration: 47.436686ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T01:24:10.687522Z","caller":"traceutil/trace.go:171","msg":"trace[1896668640] linearizableReadLoop","detail":"{readStateIndex:15579; appliedIndex:15578; }","duration":"411.558242ms","start":"2026-07-29T01:24:10.275942Z","end":"2026-07-29T01:24:10.687501Z","steps":["trace[1896668640] 'read index received' (duration: 411.380599ms)","trace[1896668640] 'applied index is now lower than readState.Index' (duration: 176.813µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T01:24:10.687898Z","caller":"traceutil/trace.go:171","msg":"trace[1274375108] transaction","detail":"{read_only:false; response_revision:15075; number_of_response:1; }","duration":"545.385967ms","start":"2026-07-29T01:24:10.142495Z","end":"2026-07-29T01:24:10.687881Z","steps":["trace[1274375108] 'process raft request' (duration: 544.880537ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T01:24:10.688058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T01:24:10.142455Z","time spent":"545.479599ms","remote":"127.0.0.1:47632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7896,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T01:24:10.688373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.424054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-29T01:24:10.688463Z","caller":"traceutil/trace.go:171","msg":"trace[1979282942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15075; }","duration":"342.587978ms","start":"2026-07-29T01:24:10.345856Z","end":"2026-07-29T01:24:10.688444Z","steps":["trace[1979282942] 'agreement among raft nodes before linearized reading' (duration: 342.275861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T01:24:10.688509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T01:24:10.345837Z","time spent":"342.665189ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-29T01:24:10.688593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.91594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T01:24:10.688668Z","caller":"traceutil/trace.go:171","msg":"trace[1791926681] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15075; }","duration":"248.984222ms","start":"2026-07-29T01:24:10.439648Z","end":"2026-07-29T01:24:10.688632Z","steps":["trace[1791926681] 'agreement among raft nodes before linearized reading' (duration: 248.87696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T01:24:10.688768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.43991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-07-29T01:24:10.688797Z","caller":"traceutil/trace.go:171","msg":"trace[1798259207] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15075; }","duration":"249.507102ms","start":"2026-07-29T01:24:10.439283Z","end":"2026-07-29T01:24:10.68879Z","steps":["trace[1798259207] 'agreement among raft nodes before linearized reading' (duration: 249.41963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T01:24:10.688915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.977372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-29T01:24:10.688944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.738811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-29T01:24:10.688975Z","caller":"traceutil/trace.go:171","msg":"trace[229180701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15075; }","duration":"413.055703ms","start":"2026-07-29T01:24:10.275888Z","end":"2026-07-29T01:24:10.688944Z","steps":["trace[229180701] 'agreement among raft nodes before linearized reading' (duration: 412.971221ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:24:10.688989Z","caller":"traceutil/trace.go:171","msg":"trace[1266904591] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:15075; }","duration":"288.826023ms","start":"2026-07-29T01:24:10.400153Z","end":"2026-07-29T01:24:10.688979Z","steps":["trace[1266904591] 'agreement among raft nodes before linearized reading' (duration: 288.752231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T01:24:10.689011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T01:24:10.275863Z","time spent":"413.140245ms","remote":"127.0.0.1:52082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-29T01:24:10.68949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.828538ms","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-07-29T01:24:10.68958Z","caller":"traceutil/trace.go:171","msg":"trace[1736594229] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15075; }","duration":"248.9399ms","start":"2026-07-29T01:24:10.440615Z","end":"2026-07-29T01:24:10.689555Z","steps":["trace[1736594229] 'agreement among raft nodes before linearized reading' (duration: 248.689055ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:25:06.547955Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12865} {"level":"info","ts":"2026-07-29T01:25:06.622722Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12865,"took":"71.470786ms","hash":2721956376,"current-db-size-bytes":59654144,"current-db-size":"60 MB","current-db-size-in-use-bytes":30769152,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-29T01:25:06.622799Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2721956376,"revision":12865,"compact-revision":10757} {"level":"info","ts":"2026-07-29T01:27:20.782227Z","caller":"traceutil/trace.go:171","msg":"trace[1342254588] transaction","detail":"{read_only:false; response_revision:16564; number_of_response:1; }","duration":"101.17539ms","start":"2026-07-29T01:27:20.681015Z","end":"2026-07-29T01:27:20.78219Z","steps":["trace[1342254588] 'process raft request' (duration: 78.680722ms)","trace[1342254588] 'compare' (duration: 22.301214ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T01:30:02.520462Z","caller":"traceutil/trace.go:171","msg":"trace[560950042] transaction","detail":"{read_only:false; response_revision:17663; number_of_response:1; }","duration":"169.578958ms","start":"2026-07-29T01:30:02.350842Z","end":"2026-07-29T01:30:02.520421Z","steps":["trace[560950042] 'process raft request' (duration: 133.593184ms)","trace[560950042] 'compare' (duration: 35.692757ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T01:30:03.050756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.701935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-29T01:30:03.050834Z","caller":"traceutil/trace.go:171","msg":"trace[725655922] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17664; }","duration":"313.836878ms","start":"2026-07-29T01:30:02.736978Z","end":"2026-07-29T01:30:03.050815Z","steps":["trace[725655922] 'range keys from in-memory index tree' (duration: 313.551271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T01:30:03.050868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T01:30:02.736944Z","time spent":"313.917299ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-29T01:30:03.050869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.929615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-29T01:30:03.050962Z","caller":"traceutil/trace.go:171","msg":"trace[2115530662] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:17664; }","duration":"250.086588ms","start":"2026-07-29T01:30:02.800852Z","end":"2026-07-29T01:30:03.050939Z","steps":["trace[2115530662] 'count revisions from in-memory index tree' (duration: 249.829553ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T01:30:03.051031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.407191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-29T01:30:03.051029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.194792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-29T01:30:03.051088Z","caller":"traceutil/trace.go:171","msg":"trace[1837319973] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat; range_end:; response_count:0; response_revision:17664; }","duration":"186.512413ms","start":"2026-07-29T01:30:02.864556Z","end":"2026-07-29T01:30:03.051069Z","steps":["trace[1837319973] 'range keys from in-memory index tree' (duration: 186.333819ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:30:03.051113Z","caller":"traceutil/trace.go:171","msg":"trace[1222255949] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17664; }","duration":"304.293233ms","start":"2026-07-29T01:30:02.746791Z","end":"2026-07-29T01:30:03.051084Z","steps":["trace[1222255949] 'range keys from in-memory index tree' (duration: 304.054088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T01:30:03.051149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T01:30:02.746787Z","time spent":"304.354335ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-29T01:30:03.051454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.708622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-29T01:30:03.051518Z","caller":"traceutil/trace.go:171","msg":"trace[439035074] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17664; }","duration":"304.804864ms","start":"2026-07-29T01:30:02.746693Z","end":"2026-07-29T01:30:03.051498Z","steps":["trace[439035074] 'range keys from in-memory index tree' (duration: 304.454447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T01:30:03.051619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T01:30:02.746668Z","time spent":"304.942057ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-29T01:30:03.064739Z","caller":"traceutil/trace.go:171","msg":"trace[666096800] transaction","detail":"{read_only:false; response_revision:17665; number_of_response:1; }","duration":"166.740199ms","start":"2026-07-29T01:30:02.897974Z","end":"2026-07-29T01:30:03.064714Z","steps":["trace[666096800] 'process raft request' (duration: 166.563856ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:30:03.10733Z","caller":"traceutil/trace.go:171","msg":"trace[787395532] transaction","detail":"{read_only:false; response_revision:17666; number_of_response:1; }","duration":"106.224275ms","start":"2026-07-29T01:30:03.001059Z","end":"2026-07-29T01:30:03.107283Z","steps":["trace[787395532] 'process raft request' (duration: 105.857578ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:30:06.554827Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15427} {"level":"info","ts":"2026-07-29T01:30:06.658314Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15427,"took":"99.656791ms","hash":2699969998,"current-db-size-bytes":59654144,"current-db-size":"60 MB","current-db-size-in-use-bytes":32239616,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-29T01:30:06.658376Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2699969998,"revision":15427,"compact-revision":12865} {"level":"info","ts":"2026-07-29T01:33:34.00096Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5a9e84e0e0a90b63","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-29T01:33:34.006616Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-29T01:33:34.00674Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-29T01:35:06.566222Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17752} {"level":"info","ts":"2026-07-29T01:35:06.656986Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17752,"took":"86.993513ms","hash":1489541737,"current-db-size-bytes":59654144,"current-db-size":"60 MB","current-db-size-in-use-bytes":34443264,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-29T01:35:06.657048Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1489541737,"revision":17752,"compact-revision":15427} {"level":"info","ts":"2026-07-29T01:35:50.776522Z","caller":"traceutil/trace.go:171","msg":"trace[503892144] linearizableReadLoop","detail":"{readStateIndex:21036; appliedIndex:21035; }","duration":"115.237839ms","start":"2026-07-29T01:35:50.661251Z","end":"2026-07-29T01:35:50.776489Z","steps":["trace[503892144] 'read index received' (duration: 114.979463ms)","trace[503892144] 'applied index is now lower than readState.Index' (duration: 256.866µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T01:35:50.776599Z","caller":"traceutil/trace.go:171","msg":"trace[673135877] transaction","detail":"{read_only:false; response_revision:20355; number_of_response:1; }","duration":"119.913974ms","start":"2026-07-29T01:35:50.656648Z","end":"2026-07-29T01:35:50.776562Z","steps":["trace[673135877] 'process raft request' (duration: 119.595478ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T01:35:50.776705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.412822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-29T01:35:50.776749Z","caller":"traceutil/trace.go:171","msg":"trace[94803852] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:20355; }","duration":"115.525095ms","start":"2026-07-29T01:35:50.661211Z","end":"2026-07-29T01:35:50.776736Z","steps":["trace[94803852] 'agreement among raft nodes before linearized reading' (duration: 115.403462ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T01:35:51.052542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.460861ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-29T01:35:51.052673Z","caller":"traceutil/trace.go:171","msg":"trace[1380566486] transaction","detail":"{read_only:false; response_revision:20356; number_of_response:1; }","duration":"213.349479ms","start":"2026-07-29T01:35:50.839299Z","end":"2026-07-29T01:35:51.052649Z","steps":["trace[1380566486] 'process raft request' (duration: 80.688754ms)","trace[1380566486] 'compare' (duration: 132.281526ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T01:35:51.096765Z","caller":"traceutil/trace.go:171","msg":"trace[1608964102] linearizableReadLoop","detail":"{readStateIndex:21038; appliedIndex:21036; }","duration":"253.049686ms","start":"2026-07-29T01:35:50.843675Z","end":"2026-07-29T01:35:51.096724Z","steps":["trace[1608964102] 'read index received' (duration: 76.494068ms)","trace[1608964102] 'applied index is now lower than readState.Index' (duration: 176.553788ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T01:35:51.096854Z","caller":"traceutil/trace.go:171","msg":"trace[753239824] transaction","detail":"{read_only:false; response_revision:20357; number_of_response:1; }","duration":"254.782152ms","start":"2026-07-29T01:35:50.842035Z","end":"2026-07-29T01:35:51.096818Z","steps":["trace[753239824] 'process raft request' (duration: 254.442816ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T01:35:51.097121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.403733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-29T01:35:51.097175Z","caller":"traceutil/trace.go:171","msg":"trace[618845213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20357; }","duration":"253.505556ms","start":"2026-07-29T01:35:50.843656Z","end":"2026-07-29T01:35:51.097162Z","steps":["trace[618845213] 'agreement among raft nodes before linearized reading' (duration: 253.278742ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T01:35:51.097208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.350683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-29T01:35:51.097301Z","caller":"traceutil/trace.go:171","msg":"trace[99080812] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20357; }","duration":"252.482317ms","start":"2026-07-29T01:35:50.844798Z","end":"2026-07-29T01:35:51.097281Z","steps":["trace[99080812] 'agreement among raft nodes before linearized reading' (duration: 252.168859ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:36:06.952616Z","caller":"traceutil/trace.go:171","msg":"trace[124669870] transaction","detail":"{read_only:false; response_revision:20435; number_of_response:1; }","duration":"117.844981ms","start":"2026-07-29T01:36:06.834749Z","end":"2026-07-29T01:36:06.952594Z","steps":["trace[124669870] 'process raft request' (duration: 117.732289ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:36:07.247618Z","caller":"traceutil/trace.go:171","msg":"trace[758793754] transaction","detail":"{read_only:false; response_revision:20436; number_of_response:1; }","duration":"109.221695ms","start":"2026-07-29T01:36:07.138351Z","end":"2026-07-29T01:36:07.247573Z","steps":["trace[758793754] 'process raft request' (duration: 108.96806ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:36:07.485597Z","caller":"traceutil/trace.go:171","msg":"trace[2027175721] transaction","detail":"{read_only:false; response_revision:20438; number_of_response:1; }","duration":"232.521139ms","start":"2026-07-29T01:36:07.253045Z","end":"2026-07-29T01:36:07.485566Z","steps":["trace[2027175721] 'process raft request' (duration: 134.052131ms)","trace[2027175721] 'compare' (duration: 98.308204ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T01:36:07.485798Z","caller":"traceutil/trace.go:171","msg":"trace[1935043622] transaction","detail":"{read_only:false; response_revision:20439; number_of_response:1; }","duration":"231.784474ms","start":"2026-07-29T01:36:07.253917Z","end":"2026-07-29T01:36:07.485701Z","steps":["trace[1935043622] 'process raft request' (duration: 231.608221ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:36:08.14862Z","caller":"traceutil/trace.go:171","msg":"trace[1735858813] transaction","detail":"{read_only:false; response_revision:20440; number_of_response:1; }","duration":"205.338974ms","start":"2026-07-29T01:36:07.943251Z","end":"2026-07-29T01:36:08.14859Z","steps":["trace[1735858813] 'process raft request' (duration: 205.1522ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:40:03.717222Z","caller":"traceutil/trace.go:171","msg":"trace[1270648118] linearizableReadLoop","detail":"{readStateIndex:23591; appliedIndex:23590; }","duration":"156.00102ms","start":"2026-07-29T01:40:03.561185Z","end":"2026-07-29T01:40:03.717186Z","steps":["trace[1270648118] 'read index received' (duration: 155.777136ms)","trace[1270648118] 'applied index is now lower than readState.Index' (duration: 222.944µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T01:40:03.717354Z","caller":"traceutil/trace.go:171","msg":"trace[2041950867] transaction","detail":"{read_only:false; response_revision:22838; number_of_response:1; }","duration":"167.702068ms","start":"2026-07-29T01:40:03.549614Z","end":"2026-07-29T01:40:03.717316Z","steps":["trace[2041950867] 'process raft request' (duration: 167.423592ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T01:40:03.717492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.284566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/openstackmachines.infrastructure.cluster.x-k8s.io\" ","response":"range_response_count:1 size:44554"} {"level":"info","ts":"2026-07-29T01:40:03.717535Z","caller":"traceutil/trace.go:171","msg":"trace[1994649050] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/openstackmachines.infrastructure.cluster.x-k8s.io; range_end:; response_count:1; response_revision:22838; }","duration":"156.351327ms","start":"2026-07-29T01:40:03.561171Z","end":"2026-07-29T01:40:03.717522Z","steps":["trace[1994649050] 'agreement among raft nodes before linearized reading' (duration: 156.148483ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T01:40:03.717574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.435035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-29T01:40:03.717727Z","caller":"traceutil/trace.go:171","msg":"trace[363733561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22838; }","duration":"146.659319ms","start":"2026-07-29T01:40:03.571048Z","end":"2026-07-29T01:40:03.717707Z","steps":["trace[363733561] 'agreement among raft nodes before linearized reading' (duration: 146.311622ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:40:03.93388Z","caller":"traceutil/trace.go:171","msg":"trace[612687035] transaction","detail":"{read_only:false; response_revision:22839; number_of_response:1; }","duration":"210.52596ms","start":"2026-07-29T01:40:03.723321Z","end":"2026-07-29T01:40:03.933847Z","steps":["trace[612687035] 'process raft request' (duration: 210.306166ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:40:03.945949Z","caller":"traceutil/trace.go:171","msg":"trace[2092680370] transaction","detail":"{read_only:false; response_revision:22840; number_of_response:1; }","duration":"222.581437ms","start":"2026-07-29T01:40:03.723338Z","end":"2026-07-29T01:40:03.945919Z","steps":["trace[2092680370] 'process raft request' (duration: 222.360802ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:40:03.946581Z","caller":"traceutil/trace.go:171","msg":"trace[462492062] transaction","detail":"{read_only:false; response_revision:22841; number_of_response:1; }","duration":"222.74936ms","start":"2026-07-29T01:40:03.723813Z","end":"2026-07-29T01:40:03.946562Z","steps":["trace[462492062] 'process raft request' (duration: 222.038616ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:40:03.946725Z","caller":"traceutil/trace.go:171","msg":"trace[1077675930] transaction","detail":"{read_only:false; response_revision:22843; number_of_response:1; }","duration":"171.634318ms","start":"2026-07-29T01:40:03.775062Z","end":"2026-07-29T01:40:03.946697Z","steps":["trace[1077675930] 'process raft request' (duration: 171.491775ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:40:03.946901Z","caller":"traceutil/trace.go:171","msg":"trace[1114982393] transaction","detail":"{read_only:false; response_revision:22842; number_of_response:1; }","duration":"184.639112ms","start":"2026-07-29T01:40:03.762254Z","end":"2026-07-29T01:40:03.946893Z","steps":["trace[1114982393] 'process raft request' (duration: 184.200854ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T01:40:06.580184Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20110} {"level":"info","ts":"2026-07-29T01:40:06.684976Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20110,"took":"101.487898ms","hash":3361908127,"current-db-size-bytes":59654144,"current-db-size":"60 MB","current-db-size-in-use-bytes":42389504,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-07-29T01:40:06.68528Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3361908127,"revision":20110,"compact-revision":17752} {"level":"info","ts":"2026-07-29T01:45:06.590092Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22908} {"level":"info","ts":"2026-07-29T01:45:06.716242Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22908,"took":"123.137913ms","hash":1097647611,"current-db-size-bytes":59654144,"current-db-size":"60 MB","current-db-size-in-use-bytes":32845824,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-29T01:45:06.716562Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1097647611,"revision":22908,"compact-revision":20110}