{"level":"warn","ts":"2026-04-12T20:40:16.196089Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-12T20:40:16.19619Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.62: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.62:2380","--initial-cluster=instance=https://162.253.55.62:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.62:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.62: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-04-12T20:40:16.196277Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-12T20:40:16.196292Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-04-12T20:40:16.196313Z","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-04-12T20:40:16.197052Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"]} {"level":"info","ts":"2026-04-12T20:40:16.19715Z","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.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.62: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-04-12T20:40:16.201604Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.200815ms"} {"level":"info","ts":"2026-04-12T20:40:16.209894Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"791a7e25b533cd26","cluster-id":"be484d2748da72cd"} {"level":"info","ts":"2026-04-12T20:40:16.209975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=()"} {"level":"info","ts":"2026-04-12T20:40:16.210003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 0"} {"level":"info","ts":"2026-04-12T20:40:16.210022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 791a7e25b533cd26 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-12T20:40:16.210036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 1"} {"level":"info","ts":"2026-04-12T20:40:16.210092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"warn","ts":"2026-04-12T20:40:16.217766Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-12T20:40:16.219546Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-12T20:40:16.221003Z","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-04-12T20:40:16.223868Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"791a7e25b533cd26","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-12T20:40:16.224509Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"791a7e25b533cd26","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-12T20:40:16.226475Z","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-04-12T20:40:16.227045Z","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-04-12T20:40:16.227088Z","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-04-12T20:40:16.22708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"info","ts":"2026-04-12T20:40:16.228687Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","added-peer-id":"791a7e25b533cd26","added-peer-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-04-12T20:40:16.230863Z","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-04-12T20:40:16.231156Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"791a7e25b533cd26","initial-advertise-peer-urls":["https://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-12T20:40:16.231202Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-12T20:40:16.231903Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-04-12T20:40:16.231935Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-04-12T20:40:16.510408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 is starting a new election at term 1"} {"level":"info","ts":"2026-04-12T20:40:16.510469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-12T20:40:16.510505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgPreVoteResp from 791a7e25b533cd26 at term 1"} {"level":"info","ts":"2026-04-12T20:40:16.510524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became candidate at term 2"} {"level":"info","ts":"2026-04-12T20:40:16.510533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgVoteResp from 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-04-12T20:40:16.510712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became leader at term 2"} {"level":"info","ts":"2026-04-12T20:40:16.51077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 791a7e25b533cd26 elected leader 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-04-12T20:40:16.511629Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"791a7e25b533cd26","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.62:2379]}","request-path":"/0/members/791a7e25b533cd26/attributes","cluster-id":"be484d2748da72cd","publish-timeout":"7s"} {"level":"info","ts":"2026-04-12T20:40:16.511709Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-12T20:40:16.512008Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-12T20:40:16.512092Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-12T20:40:16.51211Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-12T20:40:16.512048Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:40:16.520019Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:40:16.520207Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:40:16.52024Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:40:16.521638Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-12T20:40:16.523742Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.62:2379"} {"level":"warn","ts":"2026-04-12T20:40:52.057756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.559208ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:40:52.057899Z","caller":"traceutil/trace.go:171","msg":"trace[2130805200] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"285.570674ms","start":"2026-04-12T20:40:51.772306Z","end":"2026-04-12T20:40:52.057876Z","steps":["trace[2130805200] 'process raft request' (duration: 154.30776ms)","trace[2130805200] 'compare' (duration: 130.433237ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:40:52.344126Z","caller":"traceutil/trace.go:171","msg":"trace[330183432] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"555.803785ms","start":"2026-04-12T20:40:51.788295Z","end":"2026-04-12T20:40:52.344099Z","steps":["trace[330183432] 'process raft request' (duration: 552.344897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:40:52.344327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:40:51.788266Z","time spent":"555.944346ms","remote":"127.0.0.1:59620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:40:52.392085Z","caller":"traceutil/trace.go:171","msg":"trace[1536477333] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"169.458665ms","start":"2026-04-12T20:40:52.222586Z","end":"2026-04-12T20:40:52.392045Z","steps":["trace[1536477333] 'process raft request' (duration: 169.398735ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:40:52.393141Z","caller":"traceutil/trace.go:171","msg":"trace[815286529] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"186.378611ms","start":"2026-04-12T20:40:52.206735Z","end":"2026-04-12T20:40:52.393114Z","steps":["trace[815286529] 'process raft request' (duration: 185.096691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:40:52.926691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.33679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:40:52.926789Z","caller":"traceutil/trace.go:171","msg":"trace[150307708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"416.492601ms","start":"2026-04-12T20:40:52.510273Z","end":"2026-04-12T20:40:52.926766Z","steps":["trace[150307708] 'range keys from in-memory index tree' (duration: 416.211199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:40:52.926854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:40:52.51025Z","time spent":"416.595662ms","remote":"127.0.0.1:59520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-12T20:41:08.673693Z","caller":"traceutil/trace.go:171","msg":"trace[1442812004] linearizableReadLoop","detail":"{readStateIndex:768; appliedIndex:767; }","duration":"162.374121ms","start":"2026-04-12T20:41:08.511295Z","end":"2026-04-12T20:41:08.673669Z","steps":["trace[1442812004] 'read index received' (duration: 71.853175ms)","trace[1442812004] 'applied index is now lower than readState.Index' (duration: 90.519876ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:41:08.674041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.713769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:41:08.674101Z","caller":"traceutil/trace.go:171","msg":"trace[234730671] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"287.984264ms","start":"2026-04-12T20:41:08.386085Z","end":"2026-04-12T20:41:08.674069Z","steps":["trace[234730671] 'process raft request' (duration: 197.056838ms)","trace[234730671] 'compare' (duration: 90.421701ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:41:08.674159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.904262ms","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-04-12T20:41:08.674205Z","caller":"traceutil/trace.go:171","msg":"trace[310826257] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:740; }","duration":"145.985095ms","start":"2026-04-12T20:41:08.528209Z","end":"2026-04-12T20:41:08.674194Z","steps":["trace[310826257] 'agreement among raft nodes before linearized reading' (duration: 145.840088ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:08.674166Z","caller":"traceutil/trace.go:171","msg":"trace[553049353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:740; }","duration":"162.864736ms","start":"2026-04-12T20:41:08.511281Z","end":"2026-04-12T20:41:08.674146Z","steps":["trace[553049353] 'agreement among raft nodes before linearized reading' (duration: 162.653835ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:42:20.93472Z","caller":"traceutil/trace.go:171","msg":"trace[448481289] linearizableReadLoop","detail":"{readStateIndex:1855; appliedIndex:1854; }","duration":"170.364678ms","start":"2026-04-12T20:42:20.764325Z","end":"2026-04-12T20:42:20.934689Z","steps":["trace[448481289] 'read index received' (duration: 148.426868ms)","trace[448481289] 'applied index is now lower than readState.Index' (duration: 21.93674ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:42:20.934786Z","caller":"traceutil/trace.go:171","msg":"trace[300721873] transaction","detail":"{read_only:false; response_revision:1786; number_of_response:1; }","duration":"329.510596ms","start":"2026-04-12T20:42:20.605225Z","end":"2026-04-12T20:42:20.934736Z","steps":["trace[300721873] 'process raft request' (duration: 307.601477ms)","trace[300721873] 'compare' (duration: 21.593562ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:42:20.934978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:42:20.60519Z","time spent":"329.695761ms","remote":"127.0.0.1:59784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:42:20.935007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.622945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:42:20.935224Z","caller":"traceutil/trace.go:171","msg":"trace[1979953299] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1786; }","duration":"170.909522ms","start":"2026-04-12T20:42:20.764265Z","end":"2026-04-12T20:42:20.935175Z","steps":["trace[1979953299] 'agreement among raft nodes before linearized reading' (duration: 170.553483ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:42:21.13311Z","caller":"traceutil/trace.go:171","msg":"trace[2144119638] transaction","detail":"{read_only:false; response_revision:1787; number_of_response:1; }","duration":"101.619997ms","start":"2026-04-12T20:42:21.031451Z","end":"2026-04-12T20:42:21.133071Z","steps":["trace[2144119638] 'process raft request' (duration: 60.969132ms)","trace[2144119638] 'compare' (duration: 40.374069ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:42:21.260014Z","caller":"traceutil/trace.go:171","msg":"trace[1061871322] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"102.945989ms","start":"2026-04-12T20:42:21.157017Z","end":"2026-04-12T20:42:21.259963Z","steps":["trace[1061871322] 'process raft request' (duration: 88.82162ms)","trace[1061871322] 'compare' (duration: 13.927274ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:42:33.652018Z","caller":"traceutil/trace.go:171","msg":"trace[1093618442] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"112.19603ms","start":"2026-04-12T20:42:33.539794Z","end":"2026-04-12T20:42:33.65199Z","steps":["trace[1093618442] 'process raft request' (duration: 111.956035ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:42:55.082204Z","caller":"traceutil/trace.go:171","msg":"trace[798720373] transaction","detail":"{read_only:false; response_revision:2029; number_of_response:1; }","duration":"120.741662ms","start":"2026-04-12T20:42:54.96144Z","end":"2026-04-12T20:42:55.082181Z","steps":["trace[798720373] 'process raft request' (duration: 120.62333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:42:55.579851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.035683ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:42:55.579957Z","caller":"traceutil/trace.go:171","msg":"trace[772264055] linearizableReadLoop","detail":"{readStateIndex:2108; appliedIndex:2107; }","duration":"345.339131ms","start":"2026-04-12T20:42:55.2346Z","end":"2026-04-12T20:42:55.579939Z","steps":["trace[772264055] 'read index received' (duration: 48.342169ms)","trace[772264055] 'applied index is now lower than readState.Index' (duration: 296.995322ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:42:55.580021Z","caller":"traceutil/trace.go:171","msg":"trace[1743889694] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"363.200661ms","start":"2026-04-12T20:42:55.216808Z","end":"2026-04-12T20:42:55.580009Z","steps":["trace[1743889694] 'process raft request' (duration: 66.161089ms)","trace[1743889694] 'compare' (duration: 294.961563ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:42:55.580076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:42:55.216774Z","time spent":"363.269863ms","remote":"127.0.0.1:59784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:42:55.580379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.80701ms","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-04-12T20:42:55.58041Z","caller":"traceutil/trace.go:171","msg":"trace[13721676] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2030; }","duration":"345.868912ms","start":"2026-04-12T20:42:55.23453Z","end":"2026-04-12T20:42:55.580399Z","steps":["trace[13721676] 'agreement among raft nodes before linearized reading' (duration: 345.743479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:42:55.580438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:42:55.234488Z","time spent":"345.944133ms","remote":"127.0.0.1:48818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-12T20:42:55.607891Z","caller":"traceutil/trace.go:171","msg":"trace[2046977492] transaction","detail":"{read_only:false; response_revision:2031; number_of_response:1; }","duration":"193.667685ms","start":"2026-04-12T20:42:55.41419Z","end":"2026-04-12T20:42:55.607858Z","steps":["trace[2046977492] 'process raft request' (duration: 193.510832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:42:55.608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.281179ms","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-04-12T20:42:55.608067Z","caller":"traceutil/trace.go:171","msg":"trace[684231082] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2031; }","duration":"233.399321ms","start":"2026-04-12T20:42:55.374652Z","end":"2026-04-12T20:42:55.608051Z","steps":["trace[684231082] 'agreement among raft nodes before linearized reading' (duration: 233.226868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:42:55.608054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.651158ms","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-04-12T20:42:55.608499Z","caller":"traceutil/trace.go:171","msg":"trace[1005580752] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2031; }","duration":"294.140567ms","start":"2026-04-12T20:42:55.314336Z","end":"2026-04-12T20:42:55.608476Z","steps":["trace[1005580752] 'agreement among raft nodes before linearized reading' (duration: 293.491135ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:42:55.836298Z","caller":"traceutil/trace.go:171","msg":"trace[2115010444] transaction","detail":"{read_only:false; response_revision:2034; number_of_response:1; }","duration":"222.25854ms","start":"2026-04-12T20:42:55.614011Z","end":"2026-04-12T20:42:55.83627Z","steps":["trace[2115010444] 'process raft request' (duration: 154.800176ms)","trace[2115010444] 'compare' (duration: 67.183088ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:44:38.271285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.524183ms","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-04-12T20:44:38.271401Z","caller":"traceutil/trace.go:171","msg":"trace[1921609162] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2690; }","duration":"154.687115ms","start":"2026-04-12T20:44:38.116689Z","end":"2026-04-12T20:44:38.271376Z","steps":["trace[1921609162] 'range keys from in-memory index tree' (duration: 154.288491ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:15.795041Z","caller":"traceutil/trace.go:171","msg":"trace[1991903961] transaction","detail":"{read_only:false; response_revision:4589; number_of_response:1; }","duration":"259.923232ms","start":"2026-04-12T20:48:15.535089Z","end":"2026-04-12T20:48:15.795012Z","steps":["trace[1991903961] 'process raft request' (duration: 259.743418ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:15.806256Z","caller":"traceutil/trace.go:171","msg":"trace[176166033] transaction","detail":"{read_only:false; response_revision:4590; number_of_response:1; }","duration":"160.885317ms","start":"2026-04-12T20:48:15.645344Z","end":"2026-04-12T20:48:15.806229Z","steps":["trace[176166033] 'process raft request' (duration: 160.58621ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:27.447094Z","caller":"traceutil/trace.go:171","msg":"trace[204266232] transaction","detail":"{read_only:false; response_revision:4811; number_of_response:1; }","duration":"104.467009ms","start":"2026-04-12T20:48:27.342597Z","end":"2026-04-12T20:48:27.447064Z","steps":["trace[204266232] 'process raft request' (duration: 103.980788ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:27.628366Z","caller":"traceutil/trace.go:171","msg":"trace[1322548763] transaction","detail":"{read_only:false; response_revision:4812; number_of_response:1; }","duration":"174.96683ms","start":"2026-04-12T20:48:27.453364Z","end":"2026-04-12T20:48:27.628331Z","steps":["trace[1322548763] 'process raft request' (duration: 162.09562ms)","trace[1322548763] 'compare' (duration: 12.642205ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:48:27.629005Z","caller":"traceutil/trace.go:171","msg":"trace[1446022706] linearizableReadLoop","detail":"{readStateIndex:4996; appliedIndex:4994; }","duration":"155.707362ms","start":"2026-04-12T20:48:27.473224Z","end":"2026-04-12T20:48:27.628931Z","steps":["trace[1446022706] 'read index received' (duration: 142.117336ms)","trace[1446022706] 'applied index is now lower than readState.Index' (duration: 13.588146ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:48:27.629076Z","caller":"traceutil/trace.go:171","msg":"trace[1961888007] transaction","detail":"{read_only:false; response_revision:4813; number_of_response:1; }","duration":"175.635286ms","start":"2026-04-12T20:48:27.453413Z","end":"2026-04-12T20:48:27.629049Z","steps":["trace[1961888007] 'process raft request' (duration: 174.870678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:27.629436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.198953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-12T20:48:27.629949Z","caller":"traceutil/trace.go:171","msg":"trace[275112669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4813; }","duration":"156.718995ms","start":"2026-04-12T20:48:27.473182Z","end":"2026-04-12T20:48:27.629901Z","steps":["trace[275112669] 'agreement among raft nodes before linearized reading' (duration: 155.949518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:27.6303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.859145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:48:27.630561Z","caller":"traceutil/trace.go:171","msg":"trace[1788772783] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4813; }","duration":"119.11575ms","start":"2026-04-12T20:48:27.51143Z","end":"2026-04-12T20:48:27.630546Z","steps":["trace[1788772783] 'agreement among raft nodes before linearized reading' (duration: 118.828384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:27.630446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.009286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-12T20:48:27.630915Z","caller":"traceutil/trace.go:171","msg":"trace[728120573] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4813; }","duration":"112.532188ms","start":"2026-04-12T20:48:27.518369Z","end":"2026-04-12T20:48:27.630901Z","steps":["trace[728120573] 'agreement among raft nodes before linearized reading' (duration: 111.939674ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:49:18.452545Z","caller":"traceutil/trace.go:171","msg":"trace[870382986] transaction","detail":"{read_only:false; response_revision:5234; number_of_response:1; }","duration":"125.315286ms","start":"2026-04-12T20:49:18.327205Z","end":"2026-04-12T20:49:18.45252Z","steps":["trace[870382986] 'process raft request' (duration: 125.079571ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:49:18.452705Z","caller":"traceutil/trace.go:171","msg":"trace[1311833267] transaction","detail":"{read_only:false; response_revision:5235; number_of_response:1; }","duration":"123.687242ms","start":"2026-04-12T20:49:18.328991Z","end":"2026-04-12T20:49:18.452678Z","steps":["trace[1311833267] 'process raft request' (duration: 123.451367ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:16.550983Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2896} {"level":"info","ts":"2026-04-12T20:50:16.625301Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2896,"took":"72.803871ms","hash":1464687766,"current-db-size-bytes":53772288,"current-db-size":"54 MB","current-db-size-in-use-bytes":29515776,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-12T20:50:16.625356Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1464687766,"revision":2896,"compact-revision":-1} {"level":"info","ts":"2026-04-12T20:55:16.558665Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5637} {"level":"info","ts":"2026-04-12T20:55:16.633903Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5637,"took":"73.849128ms","hash":3374017494,"current-db-size-bytes":53772288,"current-db-size":"54 MB","current-db-size-in-use-bytes":18321408,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-12T20:55:16.633955Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3374017494,"revision":5637,"compact-revision":2896}