{"level":"warn","ts":"2026-10-03T22:09:50.463289Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-10-03T22:09:50.4634Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.189: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.189:2380","--initial-cluster=instance=https://162.253.55.189:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.189:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.189: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-10-03T22:09:50.463489Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-10-03T22:09:50.463504Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.189:2380"]}
{"level":"info","ts":"2026-10-03T22:09:50.463526Z","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-10-03T22:09:50.464777Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.189:2379"]}
{"level":"info","ts":"2026-10-03T22:09:50.464879Z","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":8,"max-cpu-available":8,"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.189:2380"],"listen-peer-urls":["https://162.253.55.189:2380"],"advertise-client-urls":["https://162.253.55.189:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.189:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.189: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-10-03T22:09:50.469327Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.194786ms"}
{"level":"info","ts":"2026-10-03T22:09:50.475462Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f14c7d724df9ef94","cluster-id":"f7bde5cbb4d65447"}
{"level":"info","ts":"2026-10-03T22:09:50.475551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 switched to configuration voters=()"}
{"level":"info","ts":"2026-10-03T22:09:50.475586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 became follower at term 0"}
{"level":"info","ts":"2026-10-03T22:09:50.475595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f14c7d724df9ef94 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-10-03T22:09:50.475608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 became follower at term 1"}
{"level":"info","ts":"2026-10-03T22:09:50.475657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 switched to configuration voters=(17387410191258611604)"}
{"level":"warn","ts":"2026-10-03T22:09:50.482005Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-10-03T22:09:50.483204Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-10-03T22:09:50.484395Z","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-10-03T22:09:50.487767Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f14c7d724df9ef94","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-10-03T22:09:50.488105Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f14c7d724df9ef94","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-10-03T22:09:50.488245Z","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-10-03T22:09:50.488336Z","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-10-03T22:09:50.488344Z","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-10-03T22:09:50.490188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 switched to configuration voters=(17387410191258611604)"}
{"level":"info","ts":"2026-10-03T22:09:50.490707Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f7bde5cbb4d65447","local-member-id":"f14c7d724df9ef94","added-peer-id":"f14c7d724df9ef94","added-peer-peer-urls":["https://162.253.55.189:2380"]}
{"level":"info","ts":"2026-10-03T22:09:50.492004Z","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-10-03T22:09:50.492171Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f14c7d724df9ef94","initial-advertise-peer-urls":["https://162.253.55.189:2380"],"listen-peer-urls":["https://162.253.55.189:2380"],"advertise-client-urls":["https://162.253.55.189:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.189:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-10-03T22:09:50.492193Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-10-03T22:09:50.492188Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.189:2380"}
{"level":"info","ts":"2026-10-03T22:09:50.49225Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.189:2380"}
{"level":"info","ts":"2026-10-03T22:09:51.276428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 is starting a new election at term 1"}
{"level":"info","ts":"2026-10-03T22:09:51.276487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 became pre-candidate at term 1"}
{"level":"info","ts":"2026-10-03T22:09:51.276511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 received MsgPreVoteResp from f14c7d724df9ef94 at term 1"}
{"level":"info","ts":"2026-10-03T22:09:51.27653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 became candidate at term 2"}
{"level":"info","ts":"2026-10-03T22:09:51.276541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 received MsgVoteResp from f14c7d724df9ef94 at term 2"}
{"level":"info","ts":"2026-10-03T22:09:51.276556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 became leader at term 2"}
{"level":"info","ts":"2026-10-03T22:09:51.276569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f14c7d724df9ef94 elected leader f14c7d724df9ef94 at term 2"}
{"level":"info","ts":"2026-10-03T22:09:51.277658Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-10-03T22:09:51.278582Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f14c7d724df9ef94","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.189:2379]}","request-path":"/0/members/f14c7d724df9ef94/attributes","cluster-id":"f7bde5cbb4d65447","publish-timeout":"7s"}
{"level":"info","ts":"2026-10-03T22:09:51.279001Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-10-03T22:09:51.279204Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-10-03T22:09:51.279225Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-10-03T22:09:51.279549Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-10-03T22:09:51.280418Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f7bde5cbb4d65447","local-member-id":"f14c7d724df9ef94","cluster-version":"3.5"}
{"level":"info","ts":"2026-10-03T22:09:51.280542Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-10-03T22:09:51.280566Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-10-03T22:09:51.282943Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-10-03T22:09:51.290998Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.189:2379"}
{"level":"info","ts":"2026-10-03T22:09:54.049486Z","caller":"traceutil/trace.go:171","msg":"trace[1661179054] linearizableReadLoop","detail":"{readStateIndex:79; appliedIndex:78; }","duration":"190.73867ms","start":"2026-10-03T22:09:53.858722Z","end":"2026-10-03T22:09:54.049461Z","steps":["trace[1661179054] 'read index received'  (duration: 131.638691ms)","trace[1661179054] 'applied index is now lower than readState.Index'  (duration: 59.099179ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-03T22:09:54.049718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.971194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"}
{"level":"info","ts":"2026-10-03T22:09:54.049821Z","caller":"traceutil/trace.go:171","msg":"trace[1755568131] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:75; }","duration":"191.096787ms","start":"2026-10-03T22:09:53.858709Z","end":"2026-10-03T22:09:54.049806Z","steps":["trace[1755568131] 'agreement among raft nodes before linearized reading'  (duration: 190.890574ms)"],"step_count":1}
{"level":"info","ts":"2026-10-03T22:09:54.049532Z","caller":"traceutil/trace.go:171","msg":"trace[966645655] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"192.821748ms","start":"2026-10-03T22:09:53.856672Z","end":"2026-10-03T22:09:54.049494Z","steps":["trace[966645655] 'process raft request'  (duration: 133.674218ms)","trace[966645655] 'compare'  (duration: 58.979307ms)"],"step_count":2}
{"level":"info","ts":"2026-10-03T22:09:54.127987Z","caller":"traceutil/trace.go:171","msg":"trace[2063644311] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"268.654977ms","start":"2026-10-03T22:09:53.859316Z","end":"2026-10-03T22:09:54.127971Z","steps":["trace[2063644311] 'process raft request'  (duration: 268.496004ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-03T22:09:54.128404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.523376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"}
{"level":"info","ts":"2026-10-03T22:09:54.128657Z","caller":"traceutil/trace.go:171","msg":"trace[1381862759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:76; }","duration":"248.659748ms","start":"2026-10-03T22:09:53.879828Z","end":"2026-10-03T22:09:54.128487Z","steps":["trace[1381862759] 'agreement among raft nodes before linearized reading'  (duration: 248.265741ms)"],"step_count":1}
{"level":"info","ts":"2026-10-03T22:10:32.612175Z","caller":"traceutil/trace.go:171","msg":"trace[1130422776] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"176.101366ms","start":"2026-10-03T22:10:32.436013Z","end":"2026-10-03T22:10:32.612114Z","steps":["trace[1130422776] 'read index received'  (duration: 171.268093ms)","trace[1130422776] 'applied index is now lower than readState.Index'  (duration: 4.832393ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-03T22:10:32.612434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.396401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"}
{"level":"info","ts":"2026-10-03T22:10:32.612345Z","caller":"traceutil/trace.go:171","msg":"trace[2068439722] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"178.125251ms","start":"2026-10-03T22:10:32.434124Z","end":"2026-10-03T22:10:32.61225Z","steps":["trace[2068439722] 'process raft request'  (duration: 173.759626ms)"],"step_count":1}
{"level":"info","ts":"2026-10-03T22:10:32.612474Z","caller":"traceutil/trace.go:171","msg":"trace[224039367] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:640; }","duration":"176.479763ms","start":"2026-10-03T22:10:32.435981Z","end":"2026-10-03T22:10:32.61246Z","steps":["trace[224039367] 'agreement among raft nodes before linearized reading'  (duration: 176.28546ms)"],"step_count":1}
{"level":"info","ts":"2026-10-03T22:10:32.622405Z","caller":"traceutil/trace.go:171","msg":"trace[856680622] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"148.715147ms","start":"2026-10-03T22:10:32.473667Z","end":"2026-10-03T22:10:32.622382Z","steps":["trace[856680622] 'process raft request'  (duration: 148.256879ms)"],"step_count":1}
{"level":"info","ts":"2026-10-03T22:11:25.2799Z","caller":"traceutil/trace.go:171","msg":"trace[519585506] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"125.701266ms","start":"2026-10-03T22:11:25.154173Z","end":"2026-10-03T22:11:25.279874Z","steps":["trace[519585506] 'process raft request'  (duration: 91.385705ms)","trace[519585506] 'compare'  (duration: 34.201129ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-03T22:12:14.775788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.220232ms","expected-duration":"100ms","prefix":"","request":"header:<ID:17263600309381208595 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:1832 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:458 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >>","response":"size:16"}
{"level":"info","ts":"2026-10-03T22:12:14.775963Z","caller":"traceutil/trace.go:171","msg":"trace[1892215064] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"233.639799ms","start":"2026-10-03T22:12:14.542306Z","end":"2026-10-03T22:12:14.775946Z","steps":["trace[1892215064] 'process raft request'  (duration: 233.578568ms)"],"step_count":1}
{"level":"info","ts":"2026-10-03T22:12:14.775962Z","caller":"traceutil/trace.go:171","msg":"trace[1777840238] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"359.939824ms","start":"2026-10-03T22:12:14.415989Z","end":"2026-10-03T22:12:14.775929Z","steps":["trace[1777840238] 'process raft request'  (duration: 252.005701ms)","trace[1777840238] 'compare'  (duration: 107.11036ms)"],"step_count":2}
{"level":"info","ts":"2026-10-03T22:12:14.776131Z","caller":"traceutil/trace.go:171","msg":"trace[191895880] linearizableReadLoop","detail":"{readStateIndex:1967; appliedIndex:1966; }","duration":"275.434533ms","start":"2026-10-03T22:12:14.500597Z","end":"2026-10-03T22:12:14.776031Z","steps":["trace[191895880] 'read index received'  (duration: 167.398808ms)","trace[191895880] 'applied index is now lower than readState.Index'  (duration: 107.990224ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-03T22:12:14.776186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:12:14.415971Z","time spent":"360.123127ms","remote":"127.0.0.1:34780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:1832 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:458 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >"}
{"level":"warn","ts":"2026-10-03T22:12:14.776501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.868019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18db249cc854dac9\" ","response":"range_response_count:1 size:982"}
{"level":"info","ts":"2026-10-03T22:12:14.776633Z","caller":"traceutil/trace.go:171","msg":"trace[2015239990] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18db249cc854dac9; range_end:; response_count:1; response_revision:1900; }","duration":"276.058723ms","start":"2026-10-03T22:12:14.500548Z","end":"2026-10-03T22:12:14.776607Z","steps":["trace[2015239990] 'agreement among raft nodes before linearized reading'  (duration: 275.797109ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-03T22:12:14.77673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.33489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1e8c1996-b3f8-46af-ac7f-fd97b7a6098d\" ","response":"range_response_count:1 size:3747"}
{"level":"info","ts":"2026-10-03T22:12:14.77681Z","caller":"traceutil/trace.go:171","msg":"trace[293241784] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1e8c1996-b3f8-46af-ac7f-fd97b7a6098d; range_end:; response_count:1; response_revision:1900; }","duration":"115.568285ms","start":"2026-10-03T22:12:14.661223Z","end":"2026-10-03T22:12:14.776791Z","steps":["trace[293241784] 'agreement among raft nodes before linearized reading'  (duration: 115.165658ms)"],"step_count":1}
{"level":"info","ts":"2026-10-03T22:12:47.697188Z","caller":"traceutil/trace.go:171","msg":"trace[1287945936] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"121.981139ms","start":"2026-10-03T22:12:47.575177Z","end":"2026-10-03T22:12:47.697158Z","steps":["trace[1287945936] 'process raft request'  (duration: 121.844237ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-03T22:12:47.940715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.287847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"warn","ts":"2026-10-03T22:12:47.940775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.501975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-10-03T22:12:47.940809Z","caller":"traceutil/trace.go:171","msg":"trace[1597176340] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2136; }","duration":"160.475891ms","start":"2026-10-03T22:12:47.780309Z","end":"2026-10-03T22:12:47.940785Z","steps":["trace[1597176340] 'count revisions from in-memory index tree'  (duration: 160.181886ms)"],"step_count":1}
{"level":"info","ts":"2026-10-03T22:12:47.940834Z","caller":"traceutil/trace.go:171","msg":"trace[1099701247] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:2136; }","duration":"143.589026ms","start":"2026-10-03T22:12:47.797231Z","end":"2026-10-03T22:12:47.94082Z","steps":["trace[1099701247] 'count revisions from in-memory index tree'  (duration: 143.432404ms)"],"step_count":1}
{"level":"info","ts":"2026-10-03T22:14:29.211955Z","caller":"traceutil/trace.go:171","msg":"trace[1382696891] linearizableReadLoop","detail":"{readStateIndex:2887; appliedIndex:2886; }","duration":"141.158768ms","start":"2026-10-03T22:14:29.070768Z","end":"2026-10-03T22:14:29.211927Z","steps":["trace[1382696891] 'read index received'  (duration: 140.884884ms)","trace[1382696891] 'applied index is now lower than readState.Index'  (duration: 272.484µs)"],"step_count":2}
{"level":"info","ts":"2026-10-03T22:14:29.211996Z","caller":"traceutil/trace.go:171","msg":"trace[1942272960] transaction","detail":"{read_only:false; response_revision:2789; number_of_response:1; }","duration":"142.707101ms","start":"2026-10-03T22:14:29.069247Z","end":"2026-10-03T22:14:29.211954Z","steps":["trace[1942272960] 'process raft request'  (duration: 142.392607ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-03T22:14:29.212323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.522863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-10-03T22:14:29.212398Z","caller":"traceutil/trace.go:171","msg":"trace[488740081] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2789; }","duration":"141.658176ms","start":"2026-10-03T22:14:29.070722Z","end":"2026-10-03T22:14:29.21238Z","steps":["trace[488740081] 'agreement among raft nodes before linearized reading'  (duration: 141.331201ms)"],"step_count":1}
{"level":"info","ts":"2026-10-03T22:19:03.296519Z","caller":"traceutil/trace.go:171","msg":"trace[355500306] linearizableReadLoop","detail":"{readStateIndex:5175; appliedIndex:5174; }","duration":"134.234196ms","start":"2026-10-03T22:19:03.162243Z","end":"2026-10-03T22:19:03.296477Z","steps":["trace[355500306] 'read index received'  (duration: 128.229473ms)","trace[355500306] 'applied index is now lower than readState.Index'  (duration: 6.003583ms)"],"step_count":2}
{"level":"info","ts":"2026-10-03T22:19:03.29669Z","caller":"traceutil/trace.go:171","msg":"trace[1028646268] transaction","detail":"{read_only:false; response_revision:4991; number_of_response:1; }","duration":"143.133203ms","start":"2026-10-03T22:19:03.153543Z","end":"2026-10-03T22:19:03.296676Z","steps":["trace[1028646268] 'process raft request'  (duration: 136.921666ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-03T22:19:03.296873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.681034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"}
{"level":"info","ts":"2026-10-03T22:19:03.296951Z","caller":"traceutil/trace.go:171","msg":"trace[1047990064] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4991; }","duration":"101.796867ms","start":"2026-10-03T22:19:03.195133Z","end":"2026-10-03T22:19:03.29693Z","steps":["trace[1047990064] 'agreement among raft nodes before linearized reading'  (duration: 101.592712ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-03T22:19:03.296998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.734407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-10-03T22:19:03.297089Z","caller":"traceutil/trace.go:171","msg":"trace[263460092] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4991; }","duration":"134.85717ms","start":"2026-10-03T22:19:03.162207Z","end":"2026-10-03T22:19:03.297064Z","steps":["trace[263460092] 'agreement among raft nodes before linearized reading'  (duration: 134.699946ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-03T22:19:16.848129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.889615ms","expected-duration":"100ms","prefix":"","request":"header:<ID:17263600309381216638 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:5156 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >>","response":"size:16"}
{"level":"info","ts":"2026-10-03T22:19:16.848252Z","caller":"traceutil/trace.go:171","msg":"trace[1029144255] linearizableReadLoop","detail":"{readStateIndex:5361; appliedIndex:5360; }","duration":"340.077318ms","start":"2026-10-03T22:19:16.508157Z","end":"2026-10-03T22:19:16.848234Z","steps":["trace[1029144255] 'read index received'  (duration: 189.000379ms)","trace[1029144255] 'applied index is now lower than readState.Index'  (duration: 151.072389ms)"],"step_count":2}
{"level":"info","ts":"2026-10-03T22:19:16.848358Z","caller":"traceutil/trace.go:171","msg":"trace[253882377] transaction","detail":"{read_only:false; response_revision:5170; number_of_response:1; }","duration":"382.805454ms","start":"2026-10-03T22:19:16.46552Z","end":"2026-10-03T22:19:16.848326Z","steps":["trace[253882377] 'process raft request'  (duration: 231.632003ms)","trace[253882377] 'compare'  (duration: 150.67354ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-03T22:19:16.848505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:19:16.465485Z","time spent":"382.940476ms","remote":"127.0.0.1:34780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:5156 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-10-03T22:19:16.849057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.881866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"}
{"level":"info","ts":"2026-10-03T22:19:16.849137Z","caller":"traceutil/trace.go:171","msg":"trace[61948669] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5170; }","duration":"341.010709ms","start":"2026-10-03T22:19:16.508105Z","end":"2026-10-03T22:19:16.849116Z","steps":["trace[61948669] 'agreement among raft nodes before linearized reading'  (duration: 340.788324ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-03T22:19:16.849173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:19:16.508083Z","time spent":"341.081641ms","remote":"127.0.0.1:34680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10416,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "}
{"level":"warn","ts":"2026-10-03T22:19:16.849432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.290899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"}
{"level":"info","ts":"2026-10-03T22:19:16.849484Z","caller":"traceutil/trace.go:171","msg":"trace[819839886] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5170; }","duration":"143.386171ms","start":"2026-10-03T22:19:16.706082Z","end":"2026-10-03T22:19:16.849469Z","steps":["trace[819839886] 'agreement among raft nodes before linearized reading'  (duration: 143.224127ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-03T22:19:16.84948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.968157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"}
{"level":"info","ts":"2026-10-03T22:19:16.849595Z","caller":"traceutil/trace.go:171","msg":"trace[2126921369] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5170; }","duration":"230.11711ms","start":"2026-10-03T22:19:16.619466Z","end":"2026-10-03T22:19:16.849583Z","steps":["trace[2126921369] 'agreement among raft nodes before linearized reading'  (duration: 229.881155ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-03T22:19:16.849995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.656888ms","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-10-03T22:19:16.850041Z","caller":"traceutil/trace.go:171","msg":"trace[1334866983] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5170; }","duration":"254.74808ms","start":"2026-10-03T22:19:16.595281Z","end":"2026-10-03T22:19:16.850029Z","steps":["trace[1334866983] 'agreement among raft nodes before linearized reading'  (duration: 253.989053ms)"],"step_count":1}
{"level":"info","ts":"2026-10-03T22:19:17.107411Z","caller":"traceutil/trace.go:171","msg":"trace[1878032342] transaction","detail":"{read_only:false; response_revision:5172; number_of_response:1; }","duration":"252.340965ms","start":"2026-10-03T22:19:16.855038Z","end":"2026-10-03T22:19:17.107379Z","steps":["trace[1878032342] 'process raft request'  (duration: 191.433622ms)","trace[1878032342] 'compare'  (duration: 60.674078ms)"],"step_count":2}
{"level":"info","ts":"2026-10-03T22:19:17.107733Z","caller":"traceutil/trace.go:171","msg":"trace[895701114] transaction","detail":"{read_only:false; response_revision:5173; number_of_response:1; }","duration":"246.007907ms","start":"2026-10-03T22:19:16.861709Z","end":"2026-10-03T22:19:17.107717Z","steps":["trace[895701114] 'process raft request'  (duration: 245.602058ms)"],"step_count":1}
{"level":"info","ts":"2026-10-03T22:19:17.108308Z","caller":"traceutil/trace.go:171","msg":"trace[2088961599] linearizableReadLoop","detail":"{readStateIndex:5363; appliedIndex:5362; }","duration":"250.682418ms","start":"2026-10-03T22:19:16.857608Z","end":"2026-10-03T22:19:17.10829Z","steps":["trace[2088961599] 'read index received'  (duration: 86.354602ms)","trace[2088961599] 'applied index is now lower than readState.Index'  (duration: 164.326066ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-03T22:19:17.108505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.868722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"}
{"level":"info","ts":"2026-10-03T22:19:17.10854Z","caller":"traceutil/trace.go:171","msg":"trace[966611817] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5173; }","duration":"250.945794ms","start":"2026-10-03T22:19:16.857582Z","end":"2026-10-03T22:19:17.108528Z","steps":["trace[966611817] 'agreement among raft nodes before linearized reading'  (duration: 250.784111ms)"],"step_count":1}
{"level":"info","ts":"2026-10-03T22:19:17.146785Z","caller":"traceutil/trace.go:171","msg":"trace[1367984167] transaction","detail":"{read_only:false; response_revision:5174; number_of_response:1; }","duration":"113.590166ms","start":"2026-10-03T22:19:17.033128Z","end":"2026-10-03T22:19:17.146718Z","steps":["trace[1367984167] 'process raft request'  (duration: 113.399552ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-03T22:19:17.146837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.80184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"}
{"level":"info","ts":"2026-10-03T22:19:17.146901Z","caller":"traceutil/trace.go:171","msg":"trace[926345060] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5174; }","duration":"234.905622ms","start":"2026-10-03T22:19:16.911979Z","end":"2026-10-03T22:19:17.146885Z","steps":["trace[926345060] 'agreement among raft nodes before linearized reading'  (duration: 234.694968ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-03T22:19:17.147015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.982355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"}
{"level":"info","ts":"2026-10-03T22:19:17.14712Z","caller":"traceutil/trace.go:171","msg":"trace[1492394259] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5174; }","duration":"104.20867ms","start":"2026-10-03T22:19:17.042885Z","end":"2026-10-03T22:19:17.147094Z","steps":["trace[1492394259] 'agreement among raft nodes before linearized reading'  (duration: 103.801281ms)"],"step_count":1}
{"level":"info","ts":"2026-10-03T22:19:17.478502Z","caller":"traceutil/trace.go:171","msg":"trace[978412767] transaction","detail":"{read_only:false; response_revision:5180; number_of_response:1; }","duration":"100.758135ms","start":"2026-10-03T22:19:17.377712Z","end":"2026-10-03T22:19:17.47847Z","steps":["trace[978412767] 'process raft request'  (duration: 100.593582ms)"],"step_count":1}
{"level":"info","ts":"2026-10-03T22:19:51.334335Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2908}
{"level":"info","ts":"2026-10-03T22:19:51.406744Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2908,"took":"71.204373ms","hash":2947852800,"current-db-size-bytes":51724288,"current-db-size":"52 MB","current-db-size-in-use-bytes":27545600,"current-db-size-in-use":"28 MB"}
{"level":"info","ts":"2026-10-03T22:19:51.406791Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2947852800,"revision":2908,"compact-revision":-1}
{"level":"info","ts":"2026-10-03T22:24:51.341571Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5394}
{"level":"info","ts":"2026-10-03T22:24:51.413349Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5394,"took":"70.719304ms","hash":167799281,"current-db-size-bytes":51724288,"current-db-size":"52 MB","current-db-size-in-use-bytes":18554880,"current-db-size-in-use":"19 MB"}
{"level":"info","ts":"2026-10-03T22:24:51.413408Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":167799281,"revision":5394,"compact-revision":2908}