{"level":"warn","ts":"2026-09-12T03:56:41.209336Z","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-12T03:56:41.209486Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.54: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.54:2380","--initial-cluster=instance=https://162.253.55.54:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.54:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.54: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-12T03:56:41.209607Z","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-12T03:56:41.209647Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.54:2380"]} {"level":"info","ts":"2026-09-12T03:56:41.209675Z","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-12T03:56:41.21042Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.54:2379"]} {"level":"info","ts":"2026-09-12T03:56:41.210592Z","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.54:2380"],"listen-peer-urls":["https://162.253.55.54:2380"],"advertise-client-urls":["https://162.253.55.54:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.54:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.54: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-12T03:56:41.215686Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.28391ms"} {"level":"info","ts":"2026-09-12T03:56:41.223562Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"353030364a385dbf","cluster-id":"1e619908a4cffd8f"} {"level":"info","ts":"2026-09-12T03:56:41.223669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf switched to configuration voters=()"} {"level":"info","ts":"2026-09-12T03:56:41.223703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf became follower at term 0"} {"level":"info","ts":"2026-09-12T03:56:41.223727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 353030364a385dbf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-12T03:56:41.223739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf became follower at term 1"} {"level":"info","ts":"2026-09-12T03:56:41.223792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf switched to configuration voters=(3832616292623867327)"} {"level":"warn","ts":"2026-09-12T03:56:41.231636Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-12T03:56:41.234377Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-12T03:56:41.235678Z","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-12T03:56:41.239305Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"353030364a385dbf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-12T03:56:41.240281Z","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-12T03:56:41.240236Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"353030364a385dbf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-12T03:56:41.240363Z","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-12T03:56:41.240426Z","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-12T03:56:41.24404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf switched to configuration voters=(3832616292623867327)"} {"level":"info","ts":"2026-09-12T03:56:41.24426Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1e619908a4cffd8f","local-member-id":"353030364a385dbf","added-peer-id":"353030364a385dbf","added-peer-peer-urls":["https://162.253.55.54:2380"]} {"level":"info","ts":"2026-09-12T03:56:41.244742Z","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-12T03:56:41.244955Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"353030364a385dbf","initial-advertise-peer-urls":["https://162.253.55.54:2380"],"listen-peer-urls":["https://162.253.55.54:2380"],"advertise-client-urls":["https://162.253.55.54:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.54:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-12T03:56:41.244996Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-12T03:56:41.244986Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.54:2380"} {"level":"info","ts":"2026-09-12T03:56:41.245094Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.54:2380"} {"level":"info","ts":"2026-09-12T03:56:42.124496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf is starting a new election at term 1"} {"level":"info","ts":"2026-09-12T03:56:42.124611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf became pre-candidate at term 1"} {"level":"info","ts":"2026-09-12T03:56:42.124644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf received MsgPreVoteResp from 353030364a385dbf at term 1"} {"level":"info","ts":"2026-09-12T03:56:42.124666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf became candidate at term 2"} {"level":"info","ts":"2026-09-12T03:56:42.124677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf received MsgVoteResp from 353030364a385dbf at term 2"} {"level":"info","ts":"2026-09-12T03:56:42.124714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf became leader at term 2"} {"level":"info","ts":"2026-09-12T03:56:42.124735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 353030364a385dbf elected leader 353030364a385dbf at term 2"} {"level":"info","ts":"2026-09-12T03:56:42.125929Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-12T03:56:42.12703Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"353030364a385dbf","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.54:2379]}","request-path":"/0/members/353030364a385dbf/attributes","cluster-id":"1e619908a4cffd8f","publish-timeout":"7s"} {"level":"info","ts":"2026-09-12T03:56:42.127059Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-12T03:56:42.127028Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-12T03:56:42.127311Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-12T03:56:42.127369Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-12T03:56:42.127802Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1e619908a4cffd8f","local-member-id":"353030364a385dbf","cluster-version":"3.5"} {"level":"info","ts":"2026-09-12T03:56:42.128008Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-12T03:56:42.128067Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-12T03:56:42.129628Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-12T03:56:42.129958Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.54:2379"} {"level":"info","ts":"2026-09-12T03:57:29.478632Z","caller":"traceutil/trace.go:171","msg":"trace[1928278336] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"142.657583ms","start":"2026-09-12T03:57:29.335936Z","end":"2026-09-12T03:57:29.478594Z","steps":["trace[1928278336] 'process raft request' (duration: 141.932908ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T03:57:29.964379Z","caller":"traceutil/trace.go:171","msg":"trace[1817806007] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"402.908154ms","start":"2026-09-12T03:57:29.56141Z","end":"2026-09-12T03:57:29.964319Z","steps":["trace[1817806007] 'process raft request' (duration: 392.791286ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T03:57:29.964967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T03:57:29.561384Z","time spent":"403.088797ms","remote":"127.0.0.1:45180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-12T03:57:46.878913Z","caller":"traceutil/trace.go:171","msg":"trace[1639965143] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"179.606069ms","start":"2026-09-12T03:57:46.699248Z","end":"2026-09-12T03:57:46.878854Z","steps":["trace[1639965143] 'process raft request' (duration: 144.174221ms)","trace[1639965143] 'compare' (duration: 35.236315ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T03:58:42.569626Z","caller":"traceutil/trace.go:171","msg":"trace[1678321062] transaction","detail":"{read_only:false; response_revision:1483; number_of_response:1; }","duration":"136.898422ms","start":"2026-09-12T03:58:42.432705Z","end":"2026-09-12T03:58:42.569604Z","steps":["trace[1678321062] 'process raft request' (duration: 136.484468ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T03:58:42.743633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.351351ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-12T03:58:42.743898Z","caller":"traceutil/trace.go:171","msg":"trace[1500031459] linearizableReadLoop","detail":"{readStateIndex:1532; appliedIndex:1531; }","duration":"119.626159ms","start":"2026-09-12T03:58:42.624157Z","end":"2026-09-12T03:58:42.743784Z","steps":["trace[1500031459] 'read index received' (duration: 18.682511ms)","trace[1500031459] 'applied index is now lower than readState.Index' (duration: 100.942048ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T03:58:42.743898Z","caller":"traceutil/trace.go:171","msg":"trace[1013154687] transaction","detail":"{read_only:false; response_revision:1484; number_of_response:1; }","duration":"170.218928ms","start":"2026-09-12T03:58:42.573596Z","end":"2026-09-12T03:58:42.743815Z","steps":["trace[1013154687] 'process raft request' (duration: 69.25015ms)","trace[1013154687] 'compare' (duration: 100.122249ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T03:58:42.744065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.906101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-12T03:58:42.744132Z","caller":"traceutil/trace.go:171","msg":"trace[2131349757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1484; }","duration":"120.022352ms","start":"2026-09-12T03:58:42.624093Z","end":"2026-09-12T03:58:42.744116Z","steps":["trace[2131349757] 'agreement among raft nodes before linearized reading' (duration: 119.80599ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T03:59:07.182718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.443345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-12T03:59:07.182825Z","caller":"traceutil/trace.go:171","msg":"trace[483844938] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1880; }","duration":"122.623587ms","start":"2026-09-12T03:59:07.060181Z","end":"2026-09-12T03:59:07.182804Z","steps":["trace[483844938] 'count revisions from in-memory index tree' (duration: 122.277323ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T03:59:07.667892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.790391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-12T03:59:07.667935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.679446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:9032"} {"level":"info","ts":"2026-09-12T03:59:07.667986Z","caller":"traceutil/trace.go:171","msg":"trace[1996468584] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1882; }","duration":"190.948752ms","start":"2026-09-12T03:59:07.477017Z","end":"2026-09-12T03:59:07.667966Z","steps":["trace[1996468584] 'range keys from in-memory index tree' (duration: 190.70372ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T03:59:07.668Z","caller":"traceutil/trace.go:171","msg":"trace[321259497] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1882; }","duration":"226.793326ms","start":"2026-09-12T03:59:07.441194Z","end":"2026-09-12T03:59:07.667987Z","steps":["trace[321259497] 'range keys from in-memory index tree' (duration: 226.466502ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T03:59:07.668324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.509817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18d476c62c9462a3\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-09-12T03:59:07.66837Z","caller":"traceutil/trace.go:171","msg":"trace[535742714] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18d476c62c9462a3; range_end:; response_count:1; response_revision:1882; }","duration":"360.608608ms","start":"2026-09-12T03:59:07.30775Z","end":"2026-09-12T03:59:07.668359Z","steps":["trace[535742714] 'range keys from in-memory index tree' (duration: 360.335345ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T03:59:07.668399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T03:59:07.307723Z","time spent":"360.666988ms","remote":"127.0.0.1:44706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":1004,"request content":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18d476c62c9462a3\" "} {"level":"warn","ts":"2026-09-12T03:59:07.668529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.310945ms","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-12T03:59:07.668651Z","caller":"traceutil/trace.go:171","msg":"trace[762415432] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1882; }","duration":"304.491988ms","start":"2026-09-12T03:59:07.364129Z","end":"2026-09-12T03:59:07.668621Z","steps":["trace[762415432] 'range keys from in-memory index tree' (duration: 303.989472ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T03:59:07.668713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T03:59:07.364092Z","time spent":"304.605498ms","remote":"127.0.0.1:44892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-12T03:59:07.930406Z","caller":"traceutil/trace.go:171","msg":"trace[1308635009] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"184.497906ms","start":"2026-09-12T03:59:07.745882Z","end":"2026-09-12T03:59:07.93038Z","steps":["trace[1308635009] 'process raft request' (duration: 184.442876ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T03:59:07.930735Z","caller":"traceutil/trace.go:171","msg":"trace[523127199] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"256.71195ms","start":"2026-09-12T03:59:07.673978Z","end":"2026-09-12T03:59:07.93069Z","steps":["trace[523127199] 'process raft request' (duration: 211.903135ms)","trace[523127199] 'compare' (duration: 44.157829ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T03:59:07.930799Z","caller":"traceutil/trace.go:171","msg":"trace[162556345] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"252.376026ms","start":"2026-09-12T03:59:07.678405Z","end":"2026-09-12T03:59:07.930781Z","steps":["trace[162556345] 'process raft request' (duration: 251.829271ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T03:59:08.057384Z","caller":"traceutil/trace.go:171","msg":"trace[738767430] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"117.704325ms","start":"2026-09-12T03:59:07.939651Z","end":"2026-09-12T03:59:08.057355Z","steps":["trace[738767430] 'process raft request' (duration: 109.556853ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T03:59:08.058146Z","caller":"traceutil/trace.go:171","msg":"trace[778106869] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"116.636355ms","start":"2026-09-12T03:59:07.941481Z","end":"2026-09-12T03:59:08.058117Z","steps":["trace[778106869] 'process raft request' (duration: 116.335702ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T03:59:08.058603Z","caller":"traceutil/trace.go:171","msg":"trace[1422360089] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"116.981608ms","start":"2026-09-12T03:59:07.941605Z","end":"2026-09-12T03:59:08.058587Z","steps":["trace[1422360089] 'process raft request' (duration: 116.390572ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T03:59:08.058736Z","caller":"traceutil/trace.go:171","msg":"trace[12524989] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"116.705165ms","start":"2026-09-12T03:59:07.942021Z","end":"2026-09-12T03:59:08.058726Z","steps":["trace[12524989] 'process raft request' (duration: 116.060759ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T03:59:08.058852Z","caller":"traceutil/trace.go:171","msg":"trace[135827685] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"116.612834ms","start":"2026-09-12T03:59:07.94223Z","end":"2026-09-12T03:59:08.058843Z","steps":["trace[135827685] 'process raft request' (duration: 115.915768ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T03:59:40.421424Z","caller":"traceutil/trace.go:171","msg":"trace[674543810] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"100.459179ms","start":"2026-09-12T03:59:40.320946Z","end":"2026-09-12T03:59:40.421405Z","steps":["trace[674543810] 'process raft request' (duration: 100.339347ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T03:59:40.574608Z","caller":"traceutil/trace.go:171","msg":"trace[2091376018] linearizableReadLoop","detail":"{readStateIndex:2228; appliedIndex:2227; }","duration":"118.715828ms","start":"2026-09-12T03:59:40.455857Z","end":"2026-09-12T03:59:40.574573Z","steps":["trace[2091376018] 'read index received' (duration: 118.363525ms)","trace[2091376018] 'applied index is now lower than readState.Index' (duration: 350.733µs)"],"step_count":2} {"level":"info","ts":"2026-09-12T03:59:40.574794Z","caller":"traceutil/trace.go:171","msg":"trace[121983183] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"126.759393ms","start":"2026-09-12T03:59:40.447998Z","end":"2026-09-12T03:59:40.574758Z","steps":["trace[121983183] 'process raft request' (duration: 126.357939ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T03:59:40.575147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.262123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-09-12T03:59:40.575475Z","caller":"traceutil/trace.go:171","msg":"trace[682531274] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2155; }","duration":"119.603067ms","start":"2026-09-12T03:59:40.455845Z","end":"2026-09-12T03:59:40.575448Z","steps":["trace[682531274] 'agreement among raft nodes before linearized reading' (duration: 119.127452ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T03:59:40.576573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.46504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-12T03:59:40.576685Z","caller":"traceutil/trace.go:171","msg":"trace[1184932022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2155; }","duration":"100.594251ms","start":"2026-09-12T03:59:40.476074Z","end":"2026-09-12T03:59:40.576668Z","steps":["trace[1184932022] 'agreement among raft nodes before linearized reading' (duration: 100.45597ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:01:05.876313Z","caller":"traceutil/trace.go:171","msg":"trace[1557194135] linearizableReadLoop","detail":"{readStateIndex:2957; appliedIndex:2956; }","duration":"109.486877ms","start":"2026-09-12T04:01:05.766803Z","end":"2026-09-12T04:01:05.87629Z","steps":["trace[1557194135] 'read index received' (duration: 109.252061ms)","trace[1557194135] 'applied index is now lower than readState.Index' (duration: 233.936µs)"],"step_count":2} {"level":"info","ts":"2026-09-12T04:01:05.876427Z","caller":"traceutil/trace.go:171","msg":"trace[305689409] transaction","detail":"{read_only:false; response_revision:2845; number_of_response:1; }","duration":"194.453339ms","start":"2026-09-12T04:01:05.681962Z","end":"2026-09-12T04:01:05.876415Z","steps":["trace[305689409] 'process raft request' (duration: 194.178942ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:01:05.876733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.928408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.54\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-12T04:01:05.876799Z","caller":"traceutil/trace.go:171","msg":"trace[1646192268] range","detail":"{range_begin:/registry/masterleases/162.253.55.54; range_end:; response_count:1; response_revision:2845; }","duration":"110.041922ms","start":"2026-09-12T04:01:05.76674Z","end":"2026-09-12T04:01:05.876781Z","steps":["trace[1646192268] 'agreement among raft nodes before linearized reading' (duration: 109.905158ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:01:11.403601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.439489ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-12T04:01:11.403849Z","caller":"traceutil/trace.go:171","msg":"trace[1477043443] linearizableReadLoop","detail":"{readStateIndex:2992; appliedIndex:2991; }","duration":"261.019253ms","start":"2026-09-12T04:01:11.142809Z","end":"2026-09-12T04:01:11.403828Z","steps":["trace[1477043443] 'read index received' (duration: 38.151µs)","trace[1477043443] 'applied index is now lower than readState.Index' (duration: 260.980032ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T04:01:11.404161Z","caller":"traceutil/trace.go:171","msg":"trace[1829293159] transaction","detail":"{read_only:false; response_revision:2878; number_of_response:1; }","duration":"154.935ms","start":"2026-09-12T04:01:11.249156Z","end":"2026-09-12T04:01:11.404091Z","steps":["trace[1829293159] 'process raft request' (duration: 154.53849ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:01:11.525824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.009842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1296"} {"level":"info","ts":"2026-09-12T04:01:11.525894Z","caller":"traceutil/trace.go:171","msg":"trace[489121378] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2878; }","duration":"383.114265ms","start":"2026-09-12T04:01:11.142761Z","end":"2026-09-12T04:01:11.525875Z","steps":["trace[489121378] 'agreement among raft nodes before linearized reading' (duration: 261.205447ms)","trace[489121378] 'range keys from in-memory index tree' (duration: 121.730783ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T04:01:11.525927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T04:01:11.142742Z","time spent":"383.176857ms","remote":"127.0.0.1:44822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1318,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-09-12T04:03:47.065557Z","caller":"traceutil/trace.go:171","msg":"trace[1913129404] linearizableReadLoop","detail":"{readStateIndex:4893; appliedIndex:4892; }","duration":"158.391636ms","start":"2026-09-12T04:03:46.907142Z","end":"2026-09-12T04:03:47.065533Z","steps":["trace[1913129404] 'read index received' (duration: 158.214903ms)","trace[1913129404] 'applied index is now lower than readState.Index' (duration: 175.823µs)"],"step_count":2} {"level":"warn","ts":"2026-09-12T04:03:47.065919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.751842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12973"} {"level":"info","ts":"2026-09-12T04:03:47.066014Z","caller":"traceutil/trace.go:171","msg":"trace[246347557] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4690; }","duration":"158.912184ms","start":"2026-09-12T04:03:46.907079Z","end":"2026-09-12T04:03:47.065991Z","steps":["trace[246347557] 'agreement among raft nodes before linearized reading' (duration: 158.63703ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:03:47.065816Z","caller":"traceutil/trace.go:171","msg":"trace[2091864618] transaction","detail":"{read_only:false; response_revision:4690; number_of_response:1; }","duration":"187.04757ms","start":"2026-09-12T04:03:46.878621Z","end":"2026-09-12T04:03:47.065668Z","steps":["trace[2091864618] 'process raft request' (duration: 186.735175ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:03:47.133336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.966322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-b6679b988-fj54l.18d4770775dffced\" ","response":"range_response_count:1 size:895"} {"level":"info","ts":"2026-09-12T04:03:47.133416Z","caller":"traceutil/trace.go:171","msg":"trace[637274108] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-b6679b988-fj54l.18d4770775dffced; range_end:; response_count:1; response_revision:4691; }","duration":"225.055813ms","start":"2026-09-12T04:03:46.908339Z","end":"2026-09-12T04:03:47.133395Z","steps":["trace[637274108] 'agreement among raft nodes before linearized reading' (duration: 224.897641ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:03:47.133446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.420784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-09-12T04:03:47.133558Z","caller":"traceutil/trace.go:171","msg":"trace[763149434] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4691; }","duration":"193.547966ms","start":"2026-09-12T04:03:46.939979Z","end":"2026-09-12T04:03:47.133527Z","steps":["trace[763149434] 'agreement among raft nodes before linearized reading' (duration: 193.222851ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:03:47.13366Z","caller":"traceutil/trace.go:171","msg":"trace[1869102068] transaction","detail":"{read_only:false; response_revision:4691; number_of_response:1; }","duration":"118.588322ms","start":"2026-09-12T04:03:47.015052Z","end":"2026-09-12T04:03:47.13364Z","steps":["trace[1869102068] 'process raft request' (duration: 98.824063ms)","trace[1869102068] 'compare' (duration: 19.14713ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T04:03:59.28705Z","caller":"traceutil/trace.go:171","msg":"trace[722836590] linearizableReadLoop","detail":"{readStateIndex:5093; appliedIndex:5092; }","duration":"224.93229ms","start":"2026-09-12T04:03:59.062066Z","end":"2026-09-12T04:03:59.286998Z","steps":["trace[722836590] 'read index received' (duration: 224.775768ms)","trace[722836590] 'applied index is now lower than readState.Index' (duration: 154.792µs)"],"step_count":2} {"level":"info","ts":"2026-09-12T04:03:59.287144Z","caller":"traceutil/trace.go:171","msg":"trace[256684342] transaction","detail":"{read_only:false; response_revision:4882; number_of_response:1; }","duration":"229.364036ms","start":"2026-09-12T04:03:59.057752Z","end":"2026-09-12T04:03:59.287116Z","steps":["trace[256684342] 'process raft request' (duration: 229.078481ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:03:59.287311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.234616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-12T04:03:59.287367Z","caller":"traceutil/trace.go:171","msg":"trace[617873083] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4882; }","duration":"225.349027ms","start":"2026-09-12T04:03:59.062Z","end":"2026-09-12T04:03:59.287349Z","steps":["trace[617873083] 'agreement among raft nodes before linearized reading' (duration: 225.147294ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:03:59.993942Z","caller":"traceutil/trace.go:171","msg":"trace[2106542992] linearizableReadLoop","detail":"{readStateIndex:5102; appliedIndex:5101; }","duration":"146.631629ms","start":"2026-09-12T04:03:59.847288Z","end":"2026-09-12T04:03:59.99392Z","steps":["trace[2106542992] 'read index received' (duration: 146.448237ms)","trace[2106542992] 'applied index is now lower than readState.Index' (duration: 182.502µs)"],"step_count":2} {"level":"warn","ts":"2026-09-12T04:03:59.994228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.938323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-12T04:03:59.994267Z","caller":"traceutil/trace.go:171","msg":"trace[1146013633] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4891; }","duration":"147.244548ms","start":"2026-09-12T04:03:59.847011Z","end":"2026-09-12T04:03:59.994256Z","steps":["trace[1146013633] 'agreement among raft nodes before linearized reading' (duration: 146.996025ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:03:59.994397Z","caller":"traceutil/trace.go:171","msg":"trace[1483702404] transaction","detail":"{read_only:false; response_revision:4891; number_of_response:1; }","duration":"169.256641ms","start":"2026-09-12T04:03:59.825118Z","end":"2026-09-12T04:03:59.994374Z","steps":["trace[1483702404] 'process raft request' (duration: 168.681813ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:06:42.156564Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3086} {"level":"info","ts":"2026-09-12T04:06:42.283501Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3086,"took":"125.453788ms","hash":4193750619,"current-db-size-bytes":58716160,"current-db-size":"59 MB","current-db-size-in-use-bytes":31055872,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-12T04:06:42.283575Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4193750619,"revision":3086,"compact-revision":-1}