{"level":"warn","ts":"2026-03-14T07:00:09.517296Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-14T07:00:09.517412Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.70: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.70:2380","--initial-cluster=instance=https://162.253.55.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.70: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-03-14T07:00:09.517507Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-14T07:00:09.517523Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-03-14T07:00:09.517545Z","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-03-14T07:00:09.518267Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"]} {"level":"info","ts":"2026-03-14T07:00:09.518348Z","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.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.70: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-03-14T07:00:09.523539Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.948698ms"} {"level":"info","ts":"2026-03-14T07:00:09.534189Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"23887246632736aa","cluster-id":"62cfeca3ef46d3ef"} {"level":"info","ts":"2026-03-14T07:00:09.534304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=()"} {"level":"info","ts":"2026-03-14T07:00:09.534339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 0"} {"level":"info","ts":"2026-03-14T07:00:09.534366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 23887246632736aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-14T07:00:09.53438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 1"} {"level":"info","ts":"2026-03-14T07:00:09.534446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"warn","ts":"2026-03-14T07:00:09.540849Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-14T07:00:09.543135Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-14T07:00:09.54505Z","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-03-14T07:00:09.54792Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"23887246632736aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-14T07:00:09.548516Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"23887246632736aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-14T07:00:09.548715Z","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-03-14T07:00:09.548863Z","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-03-14T07:00:09.54892Z","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-03-14T07:00:09.550466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"info","ts":"2026-03-14T07:00:09.551204Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","added-peer-id":"23887246632736aa","added-peer-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-03-14T07:00:09.553305Z","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-03-14T07:00:09.553457Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-03-14T07:00:09.55352Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-03-14T07:00:09.553712Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"23887246632736aa","initial-advertise-peer-urls":["https://162.253.55.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-14T07:00:09.55378Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-14T07:00:09.835141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa is starting a new election at term 1"} {"level":"info","ts":"2026-03-14T07:00:09.835243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became pre-candidate at term 1"} {"level":"info","ts":"2026-03-14T07:00:09.835265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgPreVoteResp from 23887246632736aa at term 1"} {"level":"info","ts":"2026-03-14T07:00:09.835284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became candidate at term 2"} {"level":"info","ts":"2026-03-14T07:00:09.835292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgVoteResp from 23887246632736aa at term 2"} {"level":"info","ts":"2026-03-14T07:00:09.835312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became leader at term 2"} {"level":"info","ts":"2026-03-14T07:00:09.835324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 23887246632736aa elected leader 23887246632736aa at term 2"} {"level":"info","ts":"2026-03-14T07:00:09.836557Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"23887246632736aa","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.70:2379]}","request-path":"/0/members/23887246632736aa/attributes","cluster-id":"62cfeca3ef46d3ef","publish-timeout":"7s"} {"level":"info","ts":"2026-03-14T07:00:09.8366Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-14T07:00:09.836667Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-14T07:00:09.836789Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T07:00:09.836901Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-14T07:00:09.837103Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-14T07:00:09.837615Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T07:00:09.837783Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T07:00:09.837855Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T07:00:09.840368Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.70:2379"} {"level":"info","ts":"2026-03-14T07:00:09.840659Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-14T07:00:12.984195Z","caller":"traceutil/trace.go:171","msg":"trace[1397020431] transaction","detail":"{read_only:false; response_revision:144; number_of_response:1; }","duration":"126.510505ms","start":"2026-03-14T07:00:12.857645Z","end":"2026-03-14T07:00:12.984155Z","steps":["trace[1397020431] 'process raft request' (duration: 119.988091ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:00:47.581425Z","caller":"traceutil/trace.go:171","msg":"trace[1461737353] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"136.014131ms","start":"2026-03-14T07:00:47.445374Z","end":"2026-03-14T07:00:47.581388Z","steps":["trace[1461737353] 'process raft request' (duration: 135.817776ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:00:47.608314Z","caller":"traceutil/trace.go:171","msg":"trace[330244543] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"159.692218ms","start":"2026-03-14T07:00:47.448589Z","end":"2026-03-14T07:00:47.608281Z","steps":["trace[330244543] 'process raft request' (duration: 159.536454ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:00:47.782557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.250769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:00:47.782702Z","caller":"traceutil/trace.go:171","msg":"trace[1233269897] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:558; }","duration":"108.450944ms","start":"2026-03-14T07:00:47.67422Z","end":"2026-03-14T07:00:47.782671Z","steps":["trace[1233269897] 'range keys from in-memory index tree' (duration: 108.136217ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:08.198602Z","caller":"traceutil/trace.go:171","msg":"trace[1112385235] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"440.009672ms","start":"2026-03-14T07:01:07.758567Z","end":"2026-03-14T07:01:08.198576Z","steps":["trace[1112385235] 'process raft request' (duration: 439.827128ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:08.199077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:07.758473Z","time spent":"440.221186ms","remote":"127.0.0.1:50198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:01:08.202806Z","caller":"traceutil/trace.go:171","msg":"trace[1581860710] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"236.233206ms","start":"2026-03-14T07:01:07.966536Z","end":"2026-03-14T07:01:08.202769Z","steps":["trace[1581860710] 'process raft request' (duration: 236.136824ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:08.202831Z","caller":"traceutil/trace.go:171","msg":"trace[1961154878] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"443.990513ms","start":"2026-03-14T07:01:07.758806Z","end":"2026-03-14T07:01:08.202797Z","steps":["trace[1961154878] 'process raft request' (duration: 443.665925ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:08.203157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:07.758791Z","time spent":"444.26062ms","remote":"127.0.0.1:50198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:02:02.184345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.360762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ff36a0d6-b30e-41ea-ac25-d3dc074e0ffd\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-03-14T07:02:02.184466Z","caller":"traceutil/trace.go:171","msg":"trace[1835599888] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ff36a0d6-b30e-41ea-ac25-d3dc074e0ffd; range_end:; response_count:1; response_revision:1603; }","duration":"295.552355ms","start":"2026-03-14T07:02:01.888888Z","end":"2026-03-14T07:02:02.184441Z","steps":["trace[1835599888] 'range keys from in-memory index tree' (duration: 295.129816ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:02.184808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.064659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-03-14T07:02:02.184931Z","caller":"traceutil/trace.go:171","msg":"trace[906916676] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1603; }","duration":"200.206572ms","start":"2026-03-14T07:02:01.984695Z","end":"2026-03-14T07:02:02.184902Z","steps":["trace[906916676] 'range keys from in-memory index tree' (duration: 199.816893ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:02.18507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.88955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-14T07:02:02.185115Z","caller":"traceutil/trace.go:171","msg":"trace[1659932392] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1603; }","duration":"355.014354ms","start":"2026-03-14T07:02:01.830086Z","end":"2026-03-14T07:02:02.185101Z","steps":["trace[1659932392] 'count revisions from in-memory index tree' (duration: 354.773197ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:02.185152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:01.83005Z","time spent":"355.091365ms","remote":"127.0.0.1:50498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":30,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-03-14T07:02:02.549438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.753473ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:02:02.549598Z","caller":"traceutil/trace.go:171","msg":"trace[770458748] transaction","detail":"{read_only:false; response_revision:1605; number_of_response:1; }","duration":"264.181152ms","start":"2026-03-14T07:02:02.285387Z","end":"2026-03-14T07:02:02.549568Z","steps":["trace[770458748] 'process raft request' (duration: 155.944907ms)","trace[770458748] 'compare' (duration: 107.559969ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:02:02.893069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.910732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:02:02.893287Z","caller":"traceutil/trace.go:171","msg":"trace[2043719435] linearizableReadLoop","detail":"{readStateIndex:1671; appliedIndex:1669; }","duration":"291.965305ms","start":"2026-03-14T07:02:02.6013Z","end":"2026-03-14T07:02:02.893266Z","steps":["trace[2043719435] 'read index received' (duration: 48.619193ms)","trace[2043719435] 'applied index is now lower than readState.Index' (duration: 243.344712ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:02:02.893383Z","caller":"traceutil/trace.go:171","msg":"trace[59774261] transaction","detail":"{read_only:false; response_revision:1606; number_of_response:1; }","duration":"338.808159ms","start":"2026-03-14T07:02:02.554535Z","end":"2026-03-14T07:02:02.893344Z","steps":["trace[59774261] 'process raft request' (duration: 95.492527ms)","trace[59774261] 'compare' (duration: 242.77403ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:02:02.893443Z","caller":"traceutil/trace.go:171","msg":"trace[1791383191] transaction","detail":"{read_only:false; response_revision:1607; number_of_response:1; }","duration":"337.886567ms","start":"2026-03-14T07:02:02.555521Z","end":"2026-03-14T07:02:02.893408Z","steps":["trace[1791383191] 'process raft request' (duration: 337.685153ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:02.893569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:02.555507Z","time spent":"337.99753ms","remote":"127.0.0.1:50198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:02:02.893524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.21824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-14T07:02:02.893588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.321367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:02:02.893643Z","caller":"traceutil/trace.go:171","msg":"trace[322656418] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1607; }","duration":"292.347074ms","start":"2026-03-14T07:02:02.601283Z","end":"2026-03-14T07:02:02.89363Z","steps":["trace[322656418] 'agreement among raft nodes before linearized reading' (duration: 292.113679ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:02.893698Z","caller":"traceutil/trace.go:171","msg":"trace[1388003569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1607; }","duration":"149.480351ms","start":"2026-03-14T07:02:02.744195Z","end":"2026-03-14T07:02:02.893675Z","steps":["trace[1388003569] 'agreement among raft nodes before linearized reading' (duration: 149.299837ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:02.893574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:02.554502Z","time spent":"338.968112ms","remote":"127.0.0.1:50198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:02:47.927266Z","caller":"traceutil/trace.go:171","msg":"trace[2136433722] linearizableReadLoop","detail":"{readStateIndex:1962; appliedIndex:1961; }","duration":"138.423302ms","start":"2026-03-14T07:02:47.788816Z","end":"2026-03-14T07:02:47.927239Z","steps":["trace[2136433722] 'read index received' (duration: 138.294509ms)","trace[2136433722] 'applied index is now lower than readState.Index' (duration: 124.813µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:02:47.927458Z","caller":"traceutil/trace.go:171","msg":"trace[1542381623] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"144.329986ms","start":"2026-03-14T07:02:47.78308Z","end":"2026-03-14T07:02:47.92741Z","steps":["trace[1542381623] 'process raft request' (duration: 143.966538ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:47.927545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.710179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-03-14T07:02:47.927588Z","caller":"traceutil/trace.go:171","msg":"trace[574619543] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1887; }","duration":"138.831482ms","start":"2026-03-14T07:02:47.788742Z","end":"2026-03-14T07:02:47.927573Z","steps":["trace[574619543] 'agreement among raft nodes before linearized reading' (duration: 138.652588ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:47.927824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.28739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-14T07:02:47.927897Z","caller":"traceutil/trace.go:171","msg":"trace[215620174] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1887; }","duration":"134.433323ms","start":"2026-03-14T07:02:47.793449Z","end":"2026-03-14T07:02:47.927882Z","steps":["trace[215620174] 'agreement among raft nodes before linearized reading' (duration: 134.065945ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:53.715421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.630319ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:02:53.715569Z","caller":"traceutil/trace.go:171","msg":"trace[1051063813] linearizableReadLoop","detail":"{readStateIndex:1989; appliedIndex:1987; }","duration":"409.409726ms","start":"2026-03-14T07:02:53.306139Z","end":"2026-03-14T07:02:53.715549Z","steps":["trace[1051063813] 'read index received' (duration: 24.586133ms)","trace[1051063813] 'applied index is now lower than readState.Index' (duration: 384.821883ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:02:53.715584Z","caller":"traceutil/trace.go:171","msg":"trace[130622167] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"420.235329ms","start":"2026-03-14T07:02:53.295315Z","end":"2026-03-14T07:02:53.71555Z","steps":["trace[130622167] 'process raft request' (duration: 111.355604ms)","trace[130622167] 'compare' (duration: 308.339533ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:02:53.715719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.60808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:02:53.715777Z","caller":"traceutil/trace.go:171","msg":"trace[1765729331] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:1913; }","duration":"292.714312ms","start":"2026-03-14T07:02:53.423038Z","end":"2026-03-14T07:02:53.715752Z","steps":["trace[1765729331] 'agreement among raft nodes before linearized reading' (duration: 292.62467ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:53.715774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:53.295287Z","time spent":"420.389753ms","remote":"127.0.0.1:50198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:02:53.716016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.370683ms","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-03-14T07:02:53.715982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.821005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-14T07:02:53.716081Z","caller":"traceutil/trace.go:171","msg":"trace[929761279] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1913; }","duration":"107.488717ms","start":"2026-03-14T07:02:53.608577Z","end":"2026-03-14T07:02:53.716066Z","steps":["trace[929761279] 'agreement among raft nodes before linearized reading' (duration: 107.352854ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:53.716145Z","caller":"traceutil/trace.go:171","msg":"trace[1590205233] range","detail":"{range_begin:/registry/masterleases/162.253.55.70; range_end:; response_count:1; response_revision:1913; }","duration":"410.005729ms","start":"2026-03-14T07:02:53.306084Z","end":"2026-03-14T07:02:53.71609Z","steps":["trace[1590205233] 'agreement among raft nodes before linearized reading' (duration: 409.540379ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:53.716266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:53.306056Z","time spent":"410.193694ms","remote":"127.0.0.1:49950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/162.253.55.70\" "} {"level":"info","ts":"2026-03-14T07:04:12.348701Z","caller":"traceutil/trace.go:171","msg":"trace[1182285489] transaction","detail":"{read_only:false; response_revision:2350; number_of_response:1; }","duration":"146.135212ms","start":"2026-03-14T07:04:12.202523Z","end":"2026-03-14T07:04:12.348658Z","steps":["trace[1182285489] 'process raft request' (duration: 128.63709ms)","trace[1182285489] 'compare' (duration: 17.327468ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:04:12.350613Z","caller":"traceutil/trace.go:171","msg":"trace[1808628191] transaction","detail":"{read_only:false; response_revision:2351; number_of_response:1; }","duration":"146.801398ms","start":"2026-03-14T07:04:12.203782Z","end":"2026-03-14T07:04:12.350584Z","steps":["trace[1808628191] 'process raft request' (duration: 146.606523ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:04:12.350836Z","caller":"traceutil/trace.go:171","msg":"trace[778228192] transaction","detail":"{read_only:false; response_revision:2352; number_of_response:1; }","duration":"124.392276ms","start":"2026-03-14T07:04:12.226414Z","end":"2026-03-14T07:04:12.350807Z","steps":["trace[778228192] 'process raft request' (duration: 124.106239ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:52.818025Z","caller":"traceutil/trace.go:171","msg":"trace[2037666865] transaction","detail":"{read_only:false; response_revision:3303; number_of_response:1; }","duration":"226.068343ms","start":"2026-03-14T07:06:52.591911Z","end":"2026-03-14T07:06:52.81798Z","steps":["trace[2037666865] 'process raft request' (duration: 138.855804ms)","trace[2037666865] 'compare' (duration: 86.785399ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:08:25.890731Z","caller":"traceutil/trace.go:171","msg":"trace[1697169250] transaction","detail":"{read_only:false; response_revision:4625; number_of_response:1; }","duration":"103.046584ms","start":"2026-03-14T07:08:25.787659Z","end":"2026-03-14T07:08:25.890706Z","steps":["trace[1697169250] 'process raft request' (duration: 102.87557ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:08:25.89095Z","caller":"traceutil/trace.go:171","msg":"trace[1411404680] transaction","detail":"{read_only:false; response_revision:4626; number_of_response:1; }","duration":"103.062534ms","start":"2026-03-14T07:08:25.78786Z","end":"2026-03-14T07:08:25.890922Z","steps":["trace[1411404680] 'process raft request' (duration: 102.810068ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:08:26.044643Z","caller":"traceutil/trace.go:171","msg":"trace[1633554538] transaction","detail":"{read_only:false; response_revision:4629; number_of_response:1; }","duration":"109.263292ms","start":"2026-03-14T07:08:25.935344Z","end":"2026-03-14T07:08:26.044607Z","steps":["trace[1633554538] 'process raft request' (duration: 55.465566ms)","trace[1633554538] 'compare' (duration: 53.500449ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:08:26.045261Z","caller":"traceutil/trace.go:171","msg":"trace[2121694190] transaction","detail":"{read_only:false; response_revision:4630; number_of_response:1; }","duration":"109.556829ms","start":"2026-03-14T07:08:25.935658Z","end":"2026-03-14T07:08:26.045214Z","steps":["trace[2121694190] 'process raft request' (duration: 108.880043ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:08:33.870746Z","caller":"traceutil/trace.go:171","msg":"trace[152230874] linearizableReadLoop","detail":"{readStateIndex:4949; appliedIndex:4948; }","duration":"231.522012ms","start":"2026-03-14T07:08:33.6392Z","end":"2026-03-14T07:08:33.870722Z","steps":["trace[152230874] 'read index received' (duration: 171.361334ms)","trace[152230874] 'applied index is now lower than readState.Index' (duration: 60.159578ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:08:33.870843Z","caller":"traceutil/trace.go:171","msg":"trace[1893842454] transaction","detail":"{read_only:false; response_revision:4762; number_of_response:1; }","duration":"233.016568ms","start":"2026-03-14T07:08:33.637788Z","end":"2026-03-14T07:08:33.870804Z","steps":["trace[1893842454] 'process raft request' (duration: 172.85589ms)","trace[1893842454] 'compare' (duration: 59.942423ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:08:33.870978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.609084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:1716"} {"level":"info","ts":"2026-03-14T07:08:33.871043Z","caller":"traceutil/trace.go:171","msg":"trace[2122385021] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:4762; }","duration":"239.707096ms","start":"2026-03-14T07:08:33.631322Z","end":"2026-03-14T07:08:33.871029Z","steps":["trace[2122385021] 'agreement among raft nodes before linearized reading' (duration: 239.472011ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:08:33.871187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.317019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-03-14T07:08:33.871238Z","caller":"traceutil/trace.go:171","msg":"trace[706220772] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4762; }","duration":"242.467082ms","start":"2026-03-14T07:08:33.628759Z","end":"2026-03-14T07:08:33.871226Z","steps":["trace[706220772] 'agreement among raft nodes before linearized reading' (duration: 242.100953ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:08:33.931021Z","caller":"traceutil/trace.go:171","msg":"trace[1720646984] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4762; }","duration":"290.985272ms","start":"2026-03-14T07:08:33.639994Z","end":"2026-03-14T07:08:33.93098Z","steps":["trace[1720646984] 'process raft request' (duration: 290.754106ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:08:33.931119Z","caller":"traceutil/trace.go:171","msg":"trace[814134263] transaction","detail":"{read_only:false; response_revision:4763; number_of_response:1; }","duration":"287.659984ms","start":"2026-03-14T07:08:33.643448Z","end":"2026-03-14T07:08:33.931108Z","steps":["trace[814134263] 'process raft request' (duration: 287.403878ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:08:33.931152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.966352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-03-14T07:08:33.931062Z","caller":"traceutil/trace.go:171","msg":"trace[618952911] transaction","detail":"{read_only:false; response_revision:4764; number_of_response:1; }","duration":"287.315494ms","start":"2026-03-14T07:08:33.64372Z","end":"2026-03-14T07:08:33.931036Z","steps":["trace[618952911] 'process raft request' (duration: 287.228752ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:08:33.931305Z","caller":"traceutil/trace.go:171","msg":"trace[1472406977] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4764; }","duration":"111.120015ms","start":"2026-03-14T07:08:33.820105Z","end":"2026-03-14T07:08:33.931225Z","steps":["trace[1472406977] 'agreement among raft nodes before linearized reading' (duration: 110.926091ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:08:34.346732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.942377ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:08:34.347057Z","caller":"traceutil/trace.go:171","msg":"trace[1538856926] transaction","detail":"{read_only:false; response_revision:4765; number_of_response:1; }","duration":"411.408788ms","start":"2026-03-14T07:08:33.935612Z","end":"2026-03-14T07:08:34.347021Z","steps":["trace[1538856926] 'process raft request' (duration: 35.04784ms)","trace[1538856926] 'compare' (duration: 375.555298ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:08:34.347115Z","caller":"traceutil/trace.go:171","msg":"trace[1243428431] transaction","detail":"{read_only:false; response_revision:4766; number_of_response:1; }","duration":"410.698111ms","start":"2026-03-14T07:08:33.936385Z","end":"2026-03-14T07:08:34.347083Z","steps":["trace[1243428431] 'process raft request' (duration: 410.493857ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:08:34.347127Z","caller":"traceutil/trace.go:171","msg":"trace[1881402770] transaction","detail":"{read_only:false; response_revision:4767; number_of_response:1; }","duration":"410.580499ms","start":"2026-03-14T07:08:33.936522Z","end":"2026-03-14T07:08:34.347102Z","steps":["trace[1881402770] 'process raft request' (duration: 410.468076ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:08:34.347246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:08:33.936495Z","time spent":"410.694422ms","remote":"127.0.0.1:50198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:08:34.347269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:08:33.936359Z","time spent":"410.820135ms","remote":"127.0.0.1:50198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:08:34.347314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:08:33.935587Z","time spent":"411.555192ms","remote":"127.0.0.1:49998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":997,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:08:34.347447Z","caller":"traceutil/trace.go:171","msg":"trace[201566202] transaction","detail":"{read_only:false; response_revision:4768; number_of_response:1; }","duration":"409.445321ms","start":"2026-03-14T07:08:33.937978Z","end":"2026-03-14T07:08:34.347423Z","steps":["trace[201566202] 'process raft request' (duration: 409.070223ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:08:34.347506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:08:33.937964Z","time spent":"409.512093ms","remote":"127.0.0.1:50198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:08:34.347492Z","caller":"traceutil/trace.go:171","msg":"trace[737181819] transaction","detail":"{read_only:false; response_revision:4769; number_of_response:1; }","duration":"408.440448ms","start":"2026-03-14T07:08:33.939027Z","end":"2026-03-14T07:08:34.347468Z","steps":["trace[737181819] 'process raft request' (duration: 408.253004ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:08:34.34761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:08:33.939015Z","time spent":"408.53494ms","remote":"127.0.0.1:50118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3750,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:08:34.34778Z","caller":"traceutil/trace.go:171","msg":"trace[1992415810] linearizableReadLoop","detail":"{readStateIndex:4958; appliedIndex:4952; }","duration":"357.349006ms","start":"2026-03-14T07:08:33.9904Z","end":"2026-03-14T07:08:34.347749Z","steps":["trace[1992415810] 'read index received' (duration: 343.145349ms)","trace[1992415810] 'applied index is now lower than readState.Index' (duration: 14.201987ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:08:34.347967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.553321ms","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-03-14T07:08:34.348028Z","caller":"traceutil/trace.go:171","msg":"trace[2122167920] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4770; }","duration":"357.672874ms","start":"2026-03-14T07:08:33.990342Z","end":"2026-03-14T07:08:34.348015Z","steps":["trace[2122167920] 'agreement among raft nodes before linearized reading' (duration: 357.561202ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:08:34.348064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:08:33.990325Z","time spent":"357.731245ms","remote":"127.0.0.1:50420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-03-14T07:08:34.348057Z","caller":"traceutil/trace.go:171","msg":"trace[656598371] transaction","detail":"{read_only:false; response_revision:4770; number_of_response:1; }","duration":"398.191904ms","start":"2026-03-14T07:08:33.949618Z","end":"2026-03-14T07:08:34.34781Z","steps":["trace[656598371] 'process raft request' (duration: 397.803165ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:08:34.348198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:08:33.949601Z","time spent":"398.524863ms","remote":"127.0.0.1:53742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2250,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:08:34.348427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.12403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5tdh7\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-03-14T07:08:34.348583Z","caller":"traceutil/trace.go:171","msg":"trace[1051223963] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5tdh7; range_end:; response_count:1; response_revision:4770; }","duration":"201.341806ms","start":"2026-03-14T07:08:34.147222Z","end":"2026-03-14T07:08:34.348563Z","steps":["trace[1051223963] 'agreement among raft nodes before linearized reading' (duration: 200.728732ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:10:10.117863Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2625} {"level":"info","ts":"2026-03-14T07:10:10.174765Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2625,"took":"54.81336ms","hash":448632803,"current-db-size-bytes":41181184,"current-db-size":"41 MB","current-db-size-in-use-bytes":30388224,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-14T07:10:10.174842Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":448632803,"revision":2625,"compact-revision":-1} {"level":"info","ts":"2026-03-14T07:14:23.234595Z","caller":"traceutil/trace.go:171","msg":"trace[346935328] transaction","detail":"{read_only:false; response_revision:7370; number_of_response:1; }","duration":"315.85145ms","start":"2026-03-14T07:14:22.918715Z","end":"2026-03-14T07:14:23.234566Z","steps":["trace[346935328] 'process raft request' (duration: 315.616005ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:14:23.234775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:14:22.918698Z","time spent":"315.993004ms","remote":"127.0.0.1:50198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:14:35.522487Z","caller":"traceutil/trace.go:171","msg":"trace[1946586113] transaction","detail":"{read_only:false; response_revision:7428; number_of_response:1; }","duration":"132.821044ms","start":"2026-03-14T07:14:35.389635Z","end":"2026-03-14T07:14:35.522456Z","steps":["trace[1946586113] 'process raft request' (duration: 132.666511ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:14:35.538053Z","caller":"traceutil/trace.go:171","msg":"trace[60875207] transaction","detail":"{read_only:false; response_revision:7429; number_of_response:1; }","duration":"148.160949ms","start":"2026-03-14T07:14:35.389872Z","end":"2026-03-14T07:14:35.538032Z","steps":["trace[60875207] 'process raft request' (duration: 148.043667ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:14:36.782482Z","caller":"traceutil/trace.go:171","msg":"trace[1889593459] transaction","detail":"{read_only:false; response_revision:7435; number_of_response:1; }","duration":"102.175285ms","start":"2026-03-14T07:14:36.680267Z","end":"2026-03-14T07:14:36.782442Z","steps":["trace[1889593459] 'process raft request' (duration: 93.649754ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:15:02.69409Z","caller":"traceutil/trace.go:171","msg":"trace[1612131501] linearizableReadLoop","detail":"{readStateIndex:7893; appliedIndex:7892; }","duration":"110.0272ms","start":"2026-03-14T07:15:02.584035Z","end":"2026-03-14T07:15:02.694062Z","steps":["trace[1612131501] 'read index received' (duration: 109.787155ms)","trace[1612131501] 'applied index is now lower than readState.Index' (duration: 238.705µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:15:02.694155Z","caller":"traceutil/trace.go:171","msg":"trace[1007780848] transaction","detail":"{read_only:false; response_revision:7611; number_of_response:1; }","duration":"111.661385ms","start":"2026-03-14T07:15:02.582453Z","end":"2026-03-14T07:15:02.694115Z","steps":["trace[1007780848] 'process raft request' (duration: 111.452011ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:15:02.694508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.44278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-03-14T07:15:02.694594Z","caller":"traceutil/trace.go:171","msg":"trace[2021759535] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7611; }","duration":"110.552092ms","start":"2026-03-14T07:15:02.584019Z","end":"2026-03-14T07:15:02.694571Z","steps":["trace[2021759535] 'agreement among raft nodes before linearized reading' (duration: 110.189884ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:15:10.17239Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5628} {"level":"info","ts":"2026-03-14T07:15:10.275458Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5628,"took":"100.983129ms","hash":1005326525,"current-db-size-bytes":41902080,"current-db-size":"42 MB","current-db-size-in-use-bytes":22757376,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-03-14T07:15:10.275526Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1005326525,"revision":5628,"compact-revision":2625} {"level":"info","ts":"2026-03-14T07:15:15.489353Z","caller":"traceutil/trace.go:171","msg":"trace[84317987] transaction","detail":"{read_only:false; response_revision:7714; number_of_response:1; }","duration":"100.765255ms","start":"2026-03-14T07:15:15.38855Z","end":"2026-03-14T07:15:15.489315Z","steps":["trace[84317987] 'process raft request' (duration: 93.707125ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:15:15.490958Z","caller":"traceutil/trace.go:171","msg":"trace[1594199664] transaction","detail":"{read_only:false; number_of_response:1; response_revision:7715; }","duration":"101.479979ms","start":"2026-03-14T07:15:15.389453Z","end":"2026-03-14T07:15:15.490932Z","steps":["trace[1594199664] 'process raft request' (duration: 101.348607ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:15:16.277319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.903796ms","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-03-14T07:15:16.277412Z","caller":"traceutil/trace.go:171","msg":"trace[1568688432] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7718; }","duration":"200.022938ms","start":"2026-03-14T07:15:16.077369Z","end":"2026-03-14T07:15:16.277392Z","steps":["trace[1568688432] 'range keys from in-memory index tree' (duration: 199.720191ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:15:17.257213Z","caller":"traceutil/trace.go:171","msg":"trace[1813658523] transaction","detail":"{read_only:false; response_revision:7726; number_of_response:1; }","duration":"308.986326ms","start":"2026-03-14T07:15:16.948198Z","end":"2026-03-14T07:15:17.257184Z","steps":["trace[1813658523] 'process raft request' (duration: 308.780282ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:15:17.257218Z","caller":"traceutil/trace.go:171","msg":"trace[1487754051] linearizableReadLoop","detail":"{readStateIndex:8017; appliedIndex:8016; }","duration":"190.325642ms","start":"2026-03-14T07:15:17.066849Z","end":"2026-03-14T07:15:17.257175Z","steps":["trace[1487754051] 'read index received' (duration: 190.029056ms)","trace[1487754051] 'applied index is now lower than readState.Index' (duration: 294.226µs)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:15:17.257424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:15:16.948172Z","time spent":"309.1429ms","remote":"127.0.0.1:50198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:15:17.257436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.575057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5146"} {"level":"info","ts":"2026-03-14T07:15:17.257523Z","caller":"traceutil/trace.go:171","msg":"trace[2135587389] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:7726; }","duration":"190.71757ms","start":"2026-03-14T07:15:17.066785Z","end":"2026-03-14T07:15:17.257503Z","steps":["trace[2135587389] 'agreement among raft nodes before linearized reading' (duration: 190.480355ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:16:24.089207Z","caller":"traceutil/trace.go:171","msg":"trace[1991357780] transaction","detail":"{read_only:false; response_revision:8328; number_of_response:1; }","duration":"100.694403ms","start":"2026-03-14T07:16:23.988466Z","end":"2026-03-14T07:16:24.089161Z","steps":["trace[1991357780] 'process raft request' (duration: 80.139497ms)","trace[1991357780] 'compare' (duration: 20.230299ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:19:22.103965Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"23887246632736aa","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-14T07:19:22.111873Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-14T07:19:22.111997Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-14T07:20:10.180101Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7680} {"level":"info","ts":"2026-03-14T07:20:10.246524Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7680,"took":"63.164808ms","hash":1996465151,"current-db-size-bytes":41902080,"current-db-size":"42 MB","current-db-size-in-use-bytes":24969216,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-03-14T07:20:10.246619Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1996465151,"revision":7680,"compact-revision":5628} {"level":"info","ts":"2026-03-14T07:25:10.185681Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9877} {"level":"info","ts":"2026-03-14T07:25:10.260389Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9877,"took":"72.549888ms","hash":2776464543,"current-db-size-bytes":41902080,"current-db-size":"42 MB","current-db-size-in-use-bytes":16470016,"current-db-size-in-use":"16 MB"} {"level":"info","ts":"2026-03-14T07:25:10.260457Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2776464543,"revision":9877,"compact-revision":7680}