{"level":"warn","ts":"2026-09-15T22:16:36.842532Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-15T22:16:36.842641Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.82: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.82:2380","--initial-cluster=instance=https://162.253.55.82:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.82:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.82: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-09-15T22:16:36.842723Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-15T22:16:36.842739Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.82:2380"]} {"level":"info","ts":"2026-09-15T22:16:36.842762Z","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-09-15T22:16:36.843674Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.82:2379"]} {"level":"info","ts":"2026-09-15T22:16:36.843771Z","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.82:2380"],"listen-peer-urls":["https://162.253.55.82:2380"],"advertise-client-urls":["https://162.253.55.82:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.82:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.82: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-09-15T22:16:36.871516Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"27.488174ms"} {"level":"info","ts":"2026-09-15T22:16:36.90361Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c5298098e2dc0f92","cluster-id":"e15b04acbd0c3f73"} {"level":"info","ts":"2026-09-15T22:16:36.903852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5298098e2dc0f92 switched to configuration voters=()"} {"level":"info","ts":"2026-09-15T22:16:36.90388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5298098e2dc0f92 became follower at term 0"} {"level":"info","ts":"2026-09-15T22:16:36.903967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c5298098e2dc0f92 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-15T22:16:36.903981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5298098e2dc0f92 became follower at term 1"} {"level":"info","ts":"2026-09-15T22:16:36.904033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5298098e2dc0f92 switched to configuration voters=(14207027893646397330)"} {"level":"warn","ts":"2026-09-15T22:16:36.963706Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-15T22:16:36.96902Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-15T22:16:36.979724Z","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-09-15T22:16:36.983742Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c5298098e2dc0f92","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-15T22:16:36.98438Z","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-09-15T22:16:36.984499Z","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-09-15T22:16:36.984552Z","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-09-15T22:16:36.984599Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c5298098e2dc0f92","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-15T22:16:36.988517Z","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-09-15T22:16:36.988666Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.82:2380"} {"level":"info","ts":"2026-09-15T22:16:36.988741Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.82:2380"} {"level":"info","ts":"2026-09-15T22:16:36.989033Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c5298098e2dc0f92","initial-advertise-peer-urls":["https://162.253.55.82:2380"],"listen-peer-urls":["https://162.253.55.82:2380"],"advertise-client-urls":["https://162.253.55.82:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.82:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-15T22:16:36.989082Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-15T22:16:37.006291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5298098e2dc0f92 switched to configuration voters=(14207027893646397330)"} {"level":"info","ts":"2026-09-15T22:16:37.006556Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e15b04acbd0c3f73","local-member-id":"c5298098e2dc0f92","added-peer-id":"c5298098e2dc0f92","added-peer-peer-urls":["https://162.253.55.82:2380"]} {"level":"info","ts":"2026-09-15T22:16:37.804845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5298098e2dc0f92 is starting a new election at term 1"} {"level":"info","ts":"2026-09-15T22:16:37.804934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5298098e2dc0f92 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-15T22:16:37.804967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5298098e2dc0f92 received MsgPreVoteResp from c5298098e2dc0f92 at term 1"} {"level":"info","ts":"2026-09-15T22:16:37.804985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5298098e2dc0f92 became candidate at term 2"} {"level":"info","ts":"2026-09-15T22:16:37.805014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5298098e2dc0f92 received MsgVoteResp from c5298098e2dc0f92 at term 2"} {"level":"info","ts":"2026-09-15T22:16:37.805031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5298098e2dc0f92 became leader at term 2"} {"level":"info","ts":"2026-09-15T22:16:37.805044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c5298098e2dc0f92 elected leader c5298098e2dc0f92 at term 2"} {"level":"info","ts":"2026-09-15T22:16:37.806279Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c5298098e2dc0f92","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.82:2379]}","request-path":"/0/members/c5298098e2dc0f92/attributes","cluster-id":"e15b04acbd0c3f73","publish-timeout":"7s"} {"level":"info","ts":"2026-09-15T22:16:37.806316Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-15T22:16:37.806473Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T22:16:37.806522Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-15T22:16:37.806631Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-15T22:16:37.806929Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-15T22:16:37.807615Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e15b04acbd0c3f73","local-member-id":"c5298098e2dc0f92","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T22:16:37.80772Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T22:16:37.807748Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T22:16:37.809762Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-15T22:16:37.81396Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.82:2379"} {"level":"info","ts":"2026-09-15T22:17:21.879611Z","caller":"traceutil/trace.go:171","msg":"trace[278137984] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"194.647766ms","start":"2026-09-15T22:17:21.684934Z","end":"2026-09-15T22:17:21.879582Z","steps":["trace[278137984] 'process raft request' (duration: 193.979928ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:17:22.088836Z","caller":"traceutil/trace.go:171","msg":"trace[1521135932] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"129.060539ms","start":"2026-09-15T22:17:21.95975Z","end":"2026-09-15T22:17:22.08881Z","steps":["trace[1521135932] 'process raft request' (duration: 127.547596ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:17:22.344725Z","caller":"traceutil/trace.go:171","msg":"trace[530835589] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"130.000118ms","start":"2026-09-15T22:17:22.214696Z","end":"2026-09-15T22:17:22.344696Z","steps":["trace[530835589] 'read index received' (duration: 108.279326ms)","trace[530835589] 'applied index is now lower than readState.Index' (duration: 21.719572ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:17:22.344816Z","caller":"traceutil/trace.go:171","msg":"trace[70447849] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"370.972898ms","start":"2026-09-15T22:17:21.973808Z","end":"2026-09-15T22:17:22.344781Z","steps":["trace[70447849] 'process raft request' (duration: 349.240355ms)","trace[70447849] 'compare' (duration: 21.397458ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:17:22.344973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.262689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-15T22:17:22.345082Z","caller":"traceutil/trace.go:171","msg":"trace[616805267] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:590; }","duration":"130.442157ms","start":"2026-09-15T22:17:22.214625Z","end":"2026-09-15T22:17:22.345068Z","steps":["trace[616805267] 'agreement among raft nodes before linearized reading' (duration: 130.174395ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:17:22.345372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:17:21.973792Z","time spent":"371.103292ms","remote":"127.0.0.1:46840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-15T22:17:23.162563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.007669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4vbv9\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-15T22:17:23.162679Z","caller":"traceutil/trace.go:171","msg":"trace[1547283187] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4vbv9; range_end:; response_count:1; response_revision:591; }","duration":"699.178117ms","start":"2026-09-15T22:17:22.46347Z","end":"2026-09-15T22:17:23.162648Z","steps":["trace[1547283187] 'range keys from in-memory index tree' (duration: 698.738718ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:17:23.162748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:17:22.463445Z","time spent":"699.288941ms","remote":"127.0.0.1:46726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-4vbv9\" "} {"level":"info","ts":"2026-09-15T22:17:23.236065Z","caller":"traceutil/trace.go:171","msg":"trace[1632137274] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"390.898461ms","start":"2026-09-15T22:17:22.845123Z","end":"2026-09-15T22:17:23.236021Z","steps":["trace[1632137274] 'process raft request' (duration: 389.927881ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:17:23.236605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:17:22.845089Z","time spent":"391.074869ms","remote":"127.0.0.1:46664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036269,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T22:17:23.308772Z","caller":"traceutil/trace.go:171","msg":"trace[423459839] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"132.898977ms","start":"2026-09-15T22:17:23.175851Z","end":"2026-09-15T22:17:23.30875Z","steps":["trace[423459839] 'process raft request' (duration: 124.777443ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:17:38.296464Z","caller":"traceutil/trace.go:171","msg":"trace[1773989059] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"110.229122ms","start":"2026-09-15T22:17:38.186171Z","end":"2026-09-15T22:17:38.296401Z","steps":["trace[1773989059] 'process raft request' (duration: 109.930292ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:17:41.089713Z","caller":"traceutil/trace.go:171","msg":"trace[706427167] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"193.287238ms","start":"2026-09-15T22:17:40.896401Z","end":"2026-09-15T22:17:41.089688Z","steps":["trace[706427167] 'process raft request' (duration: 193.149653ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:19:00.710838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.404143ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T22:19:00.710942Z","caller":"traceutil/trace.go:171","msg":"trace[789855644] linearizableReadLoop","detail":"{readStateIndex:1912; appliedIndex:1911; }","duration":"325.176794ms","start":"2026-09-15T22:19:00.385747Z","end":"2026-09-15T22:19:00.710924Z","steps":["trace[789855644] 'read index received' (duration: 192.338293ms)","trace[789855644] 'applied index is now lower than readState.Index' (duration: 132.836781ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:19:00.710994Z","caller":"traceutil/trace.go:171","msg":"trace[577700551] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"434.237293ms","start":"2026-09-15T22:19:00.276712Z","end":"2026-09-15T22:19:00.710949Z","steps":["trace[577700551] 'process raft request' (duration: 301.400302ms)","trace[577700551] 'compare' (duration: 132.005416ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:19:00.711146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.404438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-15T22:19:00.711187Z","caller":"traceutil/trace.go:171","msg":"trace[763163316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1840; }","duration":"325.499359ms","start":"2026-09-15T22:19:00.385679Z","end":"2026-09-15T22:19:00.711178Z","steps":["trace[763163316] 'agreement among raft nodes before linearized reading' (duration: 325.343137ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:19:00.711265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:19:00.276692Z","time spent":"434.413636ms","remote":"127.0.0.1:46770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T22:19:00.711319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:19:00.385651Z","time spent":"325.655202ms","remote":"127.0.0.1:46770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-15T22:19:00.711361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.438985ms","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-09-15T22:19:00.711442Z","caller":"traceutil/trace.go:171","msg":"trace[491782754] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1840; }","duration":"235.521447ms","start":"2026-09-15T22:19:00.475894Z","end":"2026-09-15T22:19:00.711415Z","steps":["trace[491782754] 'agreement among raft nodes before linearized reading' (duration: 235.201211ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:19:01.007574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.620976ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T22:19:01.007708Z","caller":"traceutil/trace.go:171","msg":"trace[1892989411] linearizableReadLoop","detail":"{readStateIndex:1914; appliedIndex:1913; }","duration":"103.367339ms","start":"2026-09-15T22:19:00.90432Z","end":"2026-09-15T22:19:01.007688Z","steps":["trace[1892989411] 'read index received' (duration: 57.451µs)","trace[1892989411] 'applied index is now lower than readState.Index' (duration: 103.308178ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:19:01.007803Z","caller":"traceutil/trace.go:171","msg":"trace[1006463734] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"201.643878ms","start":"2026-09-15T22:19:00.806142Z","end":"2026-09-15T22:19:01.007786Z","steps":["trace[1006463734] 'process raft request' (duration: 16.672345ms)","trace[1006463734] 'compare' (duration: 184.380232ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:19:01.008195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.835497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-15T22:19:01.008308Z","caller":"traceutil/trace.go:171","msg":"trace[1469582432] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1842; }","duration":"104.030631ms","start":"2026-09-15T22:19:00.904258Z","end":"2026-09-15T22:19:01.008289Z","steps":["trace[1469582432] 'agreement among raft nodes before linearized reading' (duration: 103.747756ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:19:01.425514Z","caller":"traceutil/trace.go:171","msg":"trace[783328543] linearizableReadLoop","detail":"{readStateIndex:1916; appliedIndex:1915; }","duration":"399.08764ms","start":"2026-09-15T22:19:01.026394Z","end":"2026-09-15T22:19:01.425482Z","steps":["trace[783328543] 'read index received' (duration: 371.927462ms)","trace[783328543] 'applied index is now lower than readState.Index' (duration: 27.158568ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:19:01.425568Z","caller":"traceutil/trace.go:171","msg":"trace[2114336416] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"412.391544ms","start":"2026-09-15T22:19:01.013148Z","end":"2026-09-15T22:19:01.425539Z","steps":["trace[2114336416] 'process raft request' (duration: 385.044233ms)","trace[2114336416] 'compare' (duration: 26.855673ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:19:01.425819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.489415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-91cb4025-b0f7-4e58-ac5f-e677f59d3d98\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-09-15T22:19:01.425825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:19:01.013121Z","time spent":"412.506107ms","remote":"127.0.0.1:46770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T22:19:01.42588Z","caller":"traceutil/trace.go:171","msg":"trace[1418123970] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-91cb4025-b0f7-4e58-ac5f-e677f59d3d98; range_end:; response_count:1; response_revision:1844; }","duration":"413.591385ms","start":"2026-09-15T22:19:01.012271Z","end":"2026-09-15T22:19:01.425863Z","steps":["trace[1418123970] 'agreement among raft nodes before linearized reading' (duration: 413.351932ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:19:01.425948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:19:01.012252Z","time spent":"413.667446ms","remote":"127.0.0.1:46726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-91cb4025-b0f7-4e58-ac5f-e677f59d3d98\" "} {"level":"info","ts":"2026-09-15T22:19:01.466301Z","caller":"traceutil/trace.go:171","msg":"trace[321829084] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"430.025694ms","start":"2026-09-15T22:19:01.036249Z","end":"2026-09-15T22:19:01.466274Z","steps":["trace[321829084] 'process raft request' (duration: 429.741299ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:19:01.466429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:19:01.03619Z","time spent":"430.165796ms","remote":"127.0.0.1:46726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T22:19:01.466345Z","caller":"traceutil/trace.go:171","msg":"trace[1977039496] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"189.432492ms","start":"2026-09-15T22:19:01.276879Z","end":"2026-09-15T22:19:01.466311Z","steps":["trace[1977039496] 'process raft request' (duration: 189.30446ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:19:01.466724Z","caller":"traceutil/trace.go:171","msg":"trace[1363622892] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"132.745625ms","start":"2026-09-15T22:19:01.333967Z","end":"2026-09-15T22:19:01.466713Z","steps":["trace[1363622892] 'process raft request' (duration: 132.303658ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:19:01.466754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.911795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T22:19:01.466816Z","caller":"traceutil/trace.go:171","msg":"trace[1783211391] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1847; }","duration":"259.052218ms","start":"2026-09-15T22:19:01.207742Z","end":"2026-09-15T22:19:01.466794Z","steps":["trace[1783211391] 'agreement among raft nodes before linearized reading' (duration: 258.904725ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:19:01.467008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.726603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.82\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-15T22:19:01.467043Z","caller":"traceutil/trace.go:171","msg":"trace[1225287578] range","detail":"{range_begin:/registry/masterleases/162.253.55.82; range_end:; response_count:1; response_revision:1847; }","duration":"187.798813ms","start":"2026-09-15T22:19:01.279237Z","end":"2026-09-15T22:19:01.467036Z","steps":["trace[1225287578] 'agreement among raft nodes before linearized reading' (duration: 187.688061ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:19:07.642358Z","caller":"traceutil/trace.go:171","msg":"trace[909157195] linearizableReadLoop","detail":"{readStateIndex:1993; appliedIndex:1992; }","duration":"121.909794ms","start":"2026-09-15T22:19:07.520421Z","end":"2026-09-15T22:19:07.642331Z","steps":["trace[909157195] 'read index received' (duration: 121.63916ms)","trace[909157195] 'applied index is now lower than readState.Index' (duration: 269.544µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:19:07.642482Z","caller":"traceutil/trace.go:171","msg":"trace[1374999149] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"147.064523ms","start":"2026-09-15T22:19:07.495365Z","end":"2026-09-15T22:19:07.642429Z","steps":["trace[1374999149] 'process raft request' (duration: 146.775948ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:19:07.642646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.208549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-15T22:19:07.642722Z","caller":"traceutil/trace.go:171","msg":"trace[2050734441] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1919; }","duration":"122.330572ms","start":"2026-09-15T22:19:07.520367Z","end":"2026-09-15T22:19:07.642698Z","steps":["trace[2050734441] 'agreement among raft nodes before linearized reading' (duration: 122.100628ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:19:33.669096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.577873ms","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-09-15T22:19:33.669188Z","caller":"traceutil/trace.go:171","msg":"trace[1343149126] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2077; }","duration":"129.729385ms","start":"2026-09-15T22:19:33.539433Z","end":"2026-09-15T22:19:33.669162Z","steps":["trace[1343149126] 'range keys from in-memory index tree' (duration: 129.336569ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:19:34.01709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.916654ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T22:19:34.017185Z","caller":"traceutil/trace.go:171","msg":"trace[2048020665] linearizableReadLoop","detail":"{readStateIndex:2159; appliedIndex:2158; }","duration":"233.417007ms","start":"2026-09-15T22:19:33.783752Z","end":"2026-09-15T22:19:34.017169Z","steps":["trace[2048020665] 'read index received' (duration: 78.252639ms)","trace[2048020665] 'applied index is now lower than readState.Index' (duration: 155.163198ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:19:34.01743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.66025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-15T22:19:34.01753Z","caller":"traceutil/trace.go:171","msg":"trace[1005629077] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2080; }","duration":"233.818823ms","start":"2026-09-15T22:19:33.783685Z","end":"2026-09-15T22:19:34.017503Z","steps":["trace[1005629077] 'agreement among raft nodes before linearized reading' (duration: 233.566789ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:19:34.017781Z","caller":"traceutil/trace.go:171","msg":"trace[595692766] transaction","detail":"{read_only:false; response_revision:2080; number_of_response:1; }","duration":"274.485645ms","start":"2026-09-15T22:19:33.743277Z","end":"2026-09-15T22:19:34.017763Z","steps":["trace[595692766] 'process raft request' (duration: 118.753328ms)","trace[595692766] 'compare' (duration: 154.61897ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:19:34.19218Z","caller":"traceutil/trace.go:171","msg":"trace[6901014] linearizableReadLoop","detail":"{readStateIndex:2160; appliedIndex:2159; }","duration":"142.219314ms","start":"2026-09-15T22:19:34.049921Z","end":"2026-09-15T22:19:34.19214Z","steps":["trace[6901014] 'read index received' (duration: 86.713611ms)","trace[6901014] 'applied index is now lower than readState.Index' (duration: 55.504203ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:19:34.192343Z","caller":"traceutil/trace.go:171","msg":"trace[370736790] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"170.128223ms","start":"2026-09-15T22:19:34.022162Z","end":"2026-09-15T22:19:34.19229Z","steps":["trace[370736790] 'process raft request' (duration: 114.535099ms)","trace[370736790] 'compare' (duration: 55.224918ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:19:34.193493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.557185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-15T22:19:34.193576Z","caller":"traceutil/trace.go:171","msg":"trace[1696333471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2081; }","duration":"143.720278ms","start":"2026-09-15T22:19:34.049838Z","end":"2026-09-15T22:19:34.193558Z","steps":["trace[1696333471] 'agreement among raft nodes before linearized reading' (duration: 142.55355ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:19:34.746066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.91941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T22:19:34.746354Z","caller":"traceutil/trace.go:171","msg":"trace[418700387] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2083; }","duration":"250.261045ms","start":"2026-09-15T22:19:34.496067Z","end":"2026-09-15T22:19:34.746328Z","steps":["trace[418700387] 'count revisions from in-memory index tree' (duration: 249.827308ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:20:48.038645Z","caller":"traceutil/trace.go:171","msg":"trace[1800823503] transaction","detail":"{read_only:false; response_revision:2582; number_of_response:1; }","duration":"104.749371ms","start":"2026-09-15T22:20:47.93385Z","end":"2026-09-15T22:20:48.038599Z","steps":["trace[1800823503] 'process raft request' (duration: 104.534178ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:21:22.4535Z","caller":"traceutil/trace.go:171","msg":"trace[1144407117] linearizableReadLoop","detail":"{readStateIndex:2876; appliedIndex:2875; }","duration":"176.518161ms","start":"2026-09-15T22:21:22.276956Z","end":"2026-09-15T22:21:22.453474Z","steps":["trace[1144407117] 'read index received' (duration: 176.344203ms)","trace[1144407117] 'applied index is now lower than readState.Index' (duration: 172.888µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:21:22.453574Z","caller":"traceutil/trace.go:171","msg":"trace[711947772] transaction","detail":"{read_only:false; response_revision:2772; number_of_response:1; }","duration":"223.77448ms","start":"2026-09-15T22:21:22.229757Z","end":"2026-09-15T22:21:22.453531Z","steps":["trace[711947772] 'process raft request' (duration: 223.550369ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:21:22.45372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.753883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-15T22:21:22.453757Z","caller":"traceutil/trace.go:171","msg":"trace[1262515971] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2772; }","duration":"176.843207ms","start":"2026-09-15T22:21:22.276902Z","end":"2026-09-15T22:21:22.453746Z","steps":["trace[1262515971] 'agreement among raft nodes before linearized reading' (duration: 176.664919ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:25:59.398421Z","caller":"traceutil/trace.go:171","msg":"trace[1836601213] transaction","detail":"{read_only:false; response_revision:4909; number_of_response:1; }","duration":"141.675811ms","start":"2026-09-15T22:25:59.25672Z","end":"2026-09-15T22:25:59.398396Z","steps":["trace[1836601213] 'process raft request' (duration: 141.513637ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:26:05.136584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.922228ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T22:26:05.136666Z","caller":"traceutil/trace.go:171","msg":"trace[1754296818] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4957; }","duration":"120.01401ms","start":"2026-09-15T22:26:05.016641Z","end":"2026-09-15T22:26:05.136655Z","steps":["trace[1754296818] 'range keys from in-memory index tree' (duration: 119.913058ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:26:05.136875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.273375ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T22:26:05.137035Z","caller":"traceutil/trace.go:171","msg":"trace[959025001] transaction","detail":"{read_only:false; response_revision:4958; number_of_response:1; }","duration":"162.226202ms","start":"2026-09-15T22:26:04.97476Z","end":"2026-09-15T22:26:05.136986Z","steps":["trace[959025001] 'process raft request' (duration: 33.689813ms)","trace[959025001] 'compare' (duration: 128.06596ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:26:05.15608Z","caller":"traceutil/trace.go:171","msg":"trace[650227757] linearizableReadLoop","detail":"{readStateIndex:5148; appliedIndex:5147; }","duration":"118.152376ms","start":"2026-09-15T22:26:05.037911Z","end":"2026-09-15T22:26:05.156063Z","steps":["trace[650227757] 'read index received' (duration: 118.008593ms)","trace[650227757] 'applied index is now lower than readState.Index' (duration: 142.843µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:26:05.156131Z","caller":"traceutil/trace.go:171","msg":"trace[1330463328] transaction","detail":"{read_only:false; response_revision:4959; number_of_response:1; }","duration":"118.570304ms","start":"2026-09-15T22:26:05.037533Z","end":"2026-09-15T22:26:05.156103Z","steps":["trace[1330463328] 'process raft request' (duration: 118.433321ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:26:05.156396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.475872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16231"} {"level":"info","ts":"2026-09-15T22:26:05.156457Z","caller":"traceutil/trace.go:171","msg":"trace[786962435] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4959; }","duration":"118.583994ms","start":"2026-09-15T22:26:05.03786Z","end":"2026-09-15T22:26:05.156444Z","steps":["trace[786962435] 'agreement among raft nodes before linearized reading' (duration: 118.34897ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:26:05.307741Z","caller":"traceutil/trace.go:171","msg":"trace[1981048632] transaction","detail":"{read_only:false; response_revision:4960; number_of_response:1; }","duration":"134.39392ms","start":"2026-09-15T22:26:05.173321Z","end":"2026-09-15T22:26:05.307715Z","steps":["trace[1981048632] 'process raft request' (duration: 101.644825ms)","trace[1981048632] 'compare' (duration: 32.632173ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:26:05.339244Z","caller":"traceutil/trace.go:171","msg":"trace[2097265613] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"165.660676ms","start":"2026-09-15T22:26:05.173559Z","end":"2026-09-15T22:26:05.339219Z","steps":["trace[2097265613] 'process raft request' (duration: 165.509374ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:26:05.773828Z","caller":"traceutil/trace.go:171","msg":"trace[1774369862] transaction","detail":"{read_only:false; response_revision:4969; number_of_response:1; }","duration":"307.150679ms","start":"2026-09-15T22:26:05.466645Z","end":"2026-09-15T22:26:05.773796Z","steps":["trace[1774369862] 'process raft request' (duration: 306.943165ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:26:05.773965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:26:05.466627Z","time spent":"307.276501ms","remote":"127.0.0.1:46640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":826,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-15T22:26:05.780097Z","caller":"traceutil/trace.go:171","msg":"trace[970845872] transaction","detail":"{read_only:false; response_revision:4971; number_of_response:1; }","duration":"304.791775ms","start":"2026-09-15T22:26:05.475273Z","end":"2026-09-15T22:26:05.780065Z","steps":["trace[970845872] 'process raft request' (duration: 304.730704ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:26:05.780183Z","caller":"traceutil/trace.go:171","msg":"trace[794046605] transaction","detail":"{read_only:false; response_revision:4970; number_of_response:1; }","duration":"305.213113ms","start":"2026-09-15T22:26:05.474944Z","end":"2026-09-15T22:26:05.780157Z","steps":["trace[794046605] 'process raft request' (duration: 304.892816ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:26:05.780297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:26:05.474927Z","time spent":"305.306414ms","remote":"127.0.0.1:46770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T22:26:05.780331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:26:05.475264Z","time spent":"304.961328ms","remote":"127.0.0.1:46770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T22:26:09.200248Z","caller":"traceutil/trace.go:171","msg":"trace[659012144] linearizableReadLoop","detail":"{readStateIndex:5245; appliedIndex:5244; }","duration":"132.999039ms","start":"2026-09-15T22:26:09.067232Z","end":"2026-09-15T22:26:09.200231Z","steps":["trace[659012144] 'read index received' (duration: 132.881917ms)","trace[659012144] 'applied index is now lower than readState.Index' (duration: 116.292µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:26:09.200304Z","caller":"traceutil/trace.go:171","msg":"trace[2123049759] transaction","detail":"{read_only:false; response_revision:5055; number_of_response:1; }","duration":"167.534043ms","start":"2026-09-15T22:26:09.032741Z","end":"2026-09-15T22:26:09.200275Z","steps":["trace[2123049759] 'process raft request' (duration: 167.363109ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:26:09.200448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.172632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-dd3d1efb-fa58-4397-b1ee-d0ac4c3b35b3\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-09-15T22:26:09.200486Z","caller":"traceutil/trace.go:171","msg":"trace[763654986] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-dd3d1efb-fa58-4397-b1ee-d0ac4c3b35b3; range_end:; response_count:1; response_revision:5055; }","duration":"133.280455ms","start":"2026-09-15T22:26:09.067197Z","end":"2026-09-15T22:26:09.200478Z","steps":["trace[763654986] 'agreement among raft nodes before linearized reading' (duration: 133.133722ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:26:09.323158Z","caller":"traceutil/trace.go:171","msg":"trace[1219018465] transaction","detail":"{read_only:false; response_revision:5057; number_of_response:1; }","duration":"114.625486ms","start":"2026-09-15T22:26:09.208474Z","end":"2026-09-15T22:26:09.3231Z","steps":["trace[1219018465] 'process raft request' (duration: 114.435264ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:26:19.70738Z","caller":"traceutil/trace.go:171","msg":"trace[349772148] transaction","detail":"{read_only:false; response_revision:5204; number_of_response:1; }","duration":"108.593077ms","start":"2026-09-15T22:26:19.598761Z","end":"2026-09-15T22:26:19.707354Z","steps":["trace[349772148] 'process raft request' (duration: 108.433424ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:26:19.832877Z","caller":"traceutil/trace.go:171","msg":"trace[409547007] linearizableReadLoop","detail":"{readStateIndex:5402; appliedIndex:5399; }","duration":"111.318457ms","start":"2026-09-15T22:26:19.721536Z","end":"2026-09-15T22:26:19.832854Z","steps":["trace[409547007] 'read index received' (duration: 1.862214ms)","trace[409547007] 'applied index is now lower than readState.Index' (duration: 109.455163ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:26:19.833029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.440119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-15T22:26:19.833092Z","caller":"traceutil/trace.go:171","msg":"trace[127085477] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:5207; }","duration":"111.702204ms","start":"2026-09-15T22:26:19.72135Z","end":"2026-09-15T22:26:19.833052Z","steps":["trace[127085477] 'agreement among raft nodes before linearized reading' (duration: 111.593152ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:26:19.83315Z","caller":"traceutil/trace.go:171","msg":"trace[408148591] transaction","detail":"{read_only:false; response_revision:5206; number_of_response:1; }","duration":"119.067089ms","start":"2026-09-15T22:26:19.714053Z","end":"2026-09-15T22:26:19.83312Z","steps":["trace[408148591] 'process raft request' (duration: 82.589418ms)","trace[408148591] 'compare' (duration: 35.545845ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:26:19.833324Z","caller":"traceutil/trace.go:171","msg":"trace[862805] transaction","detail":"{read_only:false; response_revision:5207; number_of_response:1; }","duration":"111.788616ms","start":"2026-09-15T22:26:19.721525Z","end":"2026-09-15T22:26:19.833313Z","steps":["trace[862805] 'process raft request' (duration: 111.230225ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:26:37.840965Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2854} {"level":"info","ts":"2026-09-15T22:26:37.930788Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2854,"took":"88.600643ms","hash":1422953234,"current-db-size-bytes":51146752,"current-db-size":"51 MB","current-db-size-in-use-bytes":27344896,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-15T22:26:37.930902Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1422953234,"revision":2854,"compact-revision":-1} {"level":"info","ts":"2026-09-15T22:30:30.154137Z","caller":"traceutil/trace.go:171","msg":"trace[1402851775] linearizableReadLoop","detail":"{readStateIndex:7264; appliedIndex:7263; }","duration":"149.787126ms","start":"2026-09-15T22:30:30.004327Z","end":"2026-09-15T22:30:30.154115Z","steps":["trace[1402851775] 'read index received' (duration: 149.579968ms)","trace[1402851775] 'applied index is now lower than readState.Index' (duration: 206.118µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:30:30.154229Z","caller":"traceutil/trace.go:171","msg":"trace[2073575055] transaction","detail":"{read_only:false; response_revision:7007; number_of_response:1; }","duration":"212.603188ms","start":"2026-09-15T22:30:29.941591Z","end":"2026-09-15T22:30:30.154194Z","steps":["trace[2073575055] 'process raft request' (duration: 212.363079ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:30:30.154342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.993054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-15T22:30:30.154372Z","caller":"traceutil/trace.go:171","msg":"trace[2125046283] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7007; }","duration":"150.071277ms","start":"2026-09-15T22:30:30.004293Z","end":"2026-09-15T22:30:30.154364Z","steps":["trace[2125046283] 'agreement among raft nodes before linearized reading' (duration: 149.913951ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:30:30.253141Z","caller":"traceutil/trace.go:171","msg":"trace[944383938] transaction","detail":"{read_only:false; response_revision:7008; number_of_response:1; }","duration":"209.690283ms","start":"2026-09-15T22:30:30.043424Z","end":"2026-09-15T22:30:30.253114Z","steps":["trace[944383938] 'process raft request' (duration: 150.878536ms)","trace[944383938] 'compare' (duration: 58.598829ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:30:30.281907Z","caller":"traceutil/trace.go:171","msg":"trace[1616299852] transaction","detail":"{read_only:false; response_revision:7009; number_of_response:1; }","duration":"123.760184ms","start":"2026-09-15T22:30:30.158123Z","end":"2026-09-15T22:30:30.281883Z","steps":["trace[1616299852] 'process raft request' (duration: 123.617639ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:31:37.848098Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5333} {"level":"info","ts":"2026-09-15T22:31:37.925448Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5333,"took":"75.747382ms","hash":4089599723,"current-db-size-bytes":51146752,"current-db-size":"51 MB","current-db-size-in-use-bytes":18423808,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-09-15T22:31:37.925508Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4089599723,"revision":5333,"compact-revision":2854}