{"level":"warn","ts":"2026-04-23T02:08:02.792754Z","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-23T02:08:02.792854Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.35: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://199.204.45.35:2380","--initial-cluster=instance=https://199.204.45.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.35: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-23T02:08:02.792948Z","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-23T02:08:02.792974Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-04-23T02:08:02.793004Z","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-23T02:08:02.793906Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"]} {"level":"info","ts":"2026-04-23T02:08:02.794022Z","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://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.35: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-23T02:08:02.801125Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.793857ms"} {"level":"info","ts":"2026-04-23T02:08:02.80614Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fb85913747d92cac","cluster-id":"636a84d5a3ba9c31"} {"level":"info","ts":"2026-04-23T02:08:02.806213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=()"} {"level":"info","ts":"2026-04-23T02:08:02.80624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 0"} {"level":"info","ts":"2026-04-23T02:08:02.806253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fb85913747d92cac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-23T02:08:02.806264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 1"} {"level":"info","ts":"2026-04-23T02:08:02.806296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"warn","ts":"2026-04-23T02:08:02.813026Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-23T02:08:02.814611Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-23T02:08:02.815786Z","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-23T02:08:02.818056Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fb85913747d92cac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-23T02:08:02.818191Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fb85913747d92cac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-23T02:08:02.818583Z","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-23T02:08:02.818634Z","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-23T02:08:02.818643Z","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-23T02:08:02.819153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"info","ts":"2026-04-23T02:08:02.819269Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","added-peer-id":"fb85913747d92cac","added-peer-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-04-23T02:08:02.822087Z","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-23T02:08:02.822324Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fb85913747d92cac","initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-23T02:08:02.822357Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-23T02:08:02.822455Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-04-23T02:08:02.822469Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-04-23T02:08:03.80789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac is starting a new election at term 1"} {"level":"info","ts":"2026-04-23T02:08:03.808126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became pre-candidate at term 1"} {"level":"info","ts":"2026-04-23T02:08:03.808235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgPreVoteResp from fb85913747d92cac at term 1"} {"level":"info","ts":"2026-04-23T02:08:03.808318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became candidate at term 2"} {"level":"info","ts":"2026-04-23T02:08:03.808347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgVoteResp from fb85913747d92cac at term 2"} {"level":"info","ts":"2026-04-23T02:08:03.808393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became leader at term 2"} {"level":"info","ts":"2026-04-23T02:08:03.808417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fb85913747d92cac elected leader fb85913747d92cac at term 2"} {"level":"info","ts":"2026-04-23T02:08:03.809464Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fb85913747d92cac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.35:2379]}","request-path":"/0/members/fb85913747d92cac/attributes","cluster-id":"636a84d5a3ba9c31","publish-timeout":"7s"} {"level":"info","ts":"2026-04-23T02:08:03.809557Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T02:08:03.809636Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T02:08:03.809703Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T02:08:03.809968Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-23T02:08:03.809992Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-23T02:08:03.811128Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T02:08:03.811271Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T02:08:03.811393Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T02:08:03.811863Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-23T02:08:03.813368Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.35:2379"} {"level":"warn","ts":"2026-04-23T02:08:05.424582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.204952ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-04-23T02:08:05.42478Z","caller":"traceutil/trace.go:171","msg":"trace[1160827358] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"346.502795ms","start":"2026-04-23T02:08:05.078211Z","end":"2026-04-23T02:08:05.424714Z","steps":["trace[1160827358] 'read index received' (duration: 31.766664ms)","trace[1160827358] 'applied index is now lower than readState.Index' (duration: 314.733941ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T02:08:05.424889Z","caller":"traceutil/trace.go:171","msg":"trace[1063112970] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"352.244381ms","start":"2026-04-23T02:08:05.072622Z","end":"2026-04-23T02:08:05.424866Z","steps":["trace[1063112970] 'process raft request' (duration: 193.840073ms)","trace[1063112970] 'compare' (duration: 156.049269ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T02:08:05.424965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T02:08:05.072614Z","time spent":"352.309991ms","remote":"127.0.0.1:44848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T02:08:05.425368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.1457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T02:08:05.425405Z","caller":"traceutil/trace.go:171","msg":"trace[813320440] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:3; }","duration":"347.18687ms","start":"2026-04-23T02:08:05.078206Z","end":"2026-04-23T02:08:05.425393Z","steps":["trace[813320440] 'agreement among raft nodes before linearized reading' (duration: 347.059379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T02:08:05.42544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T02:08:05.078175Z","time spent":"347.259692ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-04-23T02:08:05.516966Z","caller":"traceutil/trace.go:171","msg":"trace[111597245] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"372.330502ms","start":"2026-04-23T02:08:05.144606Z","end":"2026-04-23T02:08:05.516937Z","steps":["trace[111597245] 'process raft request' (duration: 372.129891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T02:08:05.5171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T02:08:05.144596Z","time spent":"372.441953ms","remote":"127.0.0.1:45204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T02:08:05.517155Z","caller":"traceutil/trace.go:171","msg":"trace[965299983] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"372.133541ms","start":"2026-04-23T02:08:05.144871Z","end":"2026-04-23T02:08:05.517005Z","steps":["trace[965299983] 'process raft request' (duration: 372.01307ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:08:05.517283Z","caller":"traceutil/trace.go:171","msg":"trace[1648533936] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"372.171881ms","start":"2026-04-23T02:08:05.145039Z","end":"2026-04-23T02:08:05.517211Z","steps":["trace[1648533936] 'process raft request' (duration: 371.909889ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:08:05.517326Z","caller":"traceutil/trace.go:171","msg":"trace[2116105490] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"372.06526ms","start":"2026-04-23T02:08:05.145245Z","end":"2026-04-23T02:08:05.517311Z","steps":["trace[2116105490] 'process raft request' (duration: 371.729478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T02:08:05.517375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T02:08:05.145232Z","time spent":"372.123511ms","remote":"127.0.0.1:45278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T02:08:05.517381Z","caller":"traceutil/trace.go:171","msg":"trace[16691596] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"372.487284ms","start":"2026-04-23T02:08:05.144861Z","end":"2026-04-23T02:08:05.517349Z","steps":["trace[16691596] 'process raft request' (duration: 372.0541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T02:08:05.517384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T02:08:05.144849Z","time spent":"372.420273ms","remote":"127.0.0.1:45278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T02:08:05.517485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T02:08:05.144834Z","time spent":"372.586874ms","remote":"127.0.0.1:45278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T02:08:05.517503Z","caller":"traceutil/trace.go:171","msg":"trace[639007448] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"372.016319ms","start":"2026-04-23T02:08:05.145425Z","end":"2026-04-23T02:08:05.517441Z","steps":["trace[639007448] 'process raft request' (duration: 371.574146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T02:08:05.517553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T02:08:05.145029Z","time spent":"372.331503ms","remote":"127.0.0.1:45278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T02:08:05.517653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T02:08:05.145403Z","time spent":"372.18178ms","remote":"127.0.0.1:45278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T02:08:05.518075Z","caller":"traceutil/trace.go:171","msg":"trace[1406177217] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"361.265814ms","start":"2026-04-23T02:08:05.156797Z","end":"2026-04-23T02:08:05.518063Z","steps":["trace[1406177217] 'process raft request' (duration: 360.230015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T02:08:05.518142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T02:08:05.156786Z","time spent":"361.330874ms","remote":"127.0.0.1:44946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T02:08:05.519914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.980262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T02:08:05.51997Z","caller":"traceutil/trace.go:171","msg":"trace[926827746] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"376.071732ms","start":"2026-04-23T02:08:05.143884Z","end":"2026-04-23T02:08:05.519956Z","steps":["trace[926827746] 'agreement among raft nodes before linearized reading' (duration: 375.932771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T02:08:05.520001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T02:08:05.143868Z","time spent":"376.125263ms","remote":"127.0.0.1:44898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-23T02:08:05.519494Z","caller":"traceutil/trace.go:171","msg":"trace[225588651] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"360.024304ms","start":"2026-04-23T02:08:05.159435Z","end":"2026-04-23T02:08:05.519459Z","steps":["trace[225588651] 'process raft request' (duration: 359.51674ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T02:08:05.520267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T02:08:05.159395Z","time spent":"360.755779ms","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T02:08:05.520311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.134402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T02:08:05.520411Z","caller":"traceutil/trace.go:171","msg":"trace[2024889269] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"306.218872ms","start":"2026-04-23T02:08:05.214116Z","end":"2026-04-23T02:08:05.520335Z","steps":["trace[2024889269] 'agreement among raft nodes before linearized reading' (duration: 306.135912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T02:08:05.520454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T02:08:05.214106Z","time spent":"306.337543ms","remote":"127.0.0.1:44898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-23T02:08:05.520798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.005838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T02:08:05.520935Z","caller":"traceutil/trace.go:171","msg":"trace[1250294317] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:0; response_revision:11; }","duration":"378.138519ms","start":"2026-04-23T02:08:05.142711Z","end":"2026-04-23T02:08:05.52085Z","steps":["trace[1250294317] 'agreement among raft nodes before linearized reading' (duration: 377.941708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T02:08:05.521111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T02:08:05.1427Z","time spent":"378.337241ms","remote":"127.0.0.1:44812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.35\" "} {"level":"warn","ts":"2026-04-23T02:08:05.521414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.441157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T02:08:05.521448Z","caller":"traceutil/trace.go:171","msg":"trace[960340864] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"401.502317ms","start":"2026-04-23T02:08:05.119938Z","end":"2026-04-23T02:08:05.52144Z","steps":["trace[960340864] 'agreement among raft nodes before linearized reading' (duration: 401.435126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T02:08:05.521469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T02:08:05.119926Z","time spent":"401.538677ms","remote":"127.0.0.1:44898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-23T02:08:05.521615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.717889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T02:08:05.521654Z","caller":"traceutil/trace.go:171","msg":"trace[2106977566] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"325.777369ms","start":"2026-04-23T02:08:05.195869Z","end":"2026-04-23T02:08:05.521647Z","steps":["trace[2106977566] 'agreement among raft nodes before linearized reading' (duration: 325.726419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T02:08:05.521701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T02:08:05.195857Z","time spent":"325.83241ms","remote":"127.0.0.1:44898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-23T02:08:05.521702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.707888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T02:08:05.521728Z","caller":"traceutil/trace.go:171","msg":"trace[1350767470] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"376.774139ms","start":"2026-04-23T02:08:05.144946Z","end":"2026-04-23T02:08:05.52172Z","steps":["trace[1350767470] 'agreement among raft nodes before linearized reading' (duration: 376.737139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T02:08:05.521751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T02:08:05.144931Z","time spent":"376.815069ms","remote":"127.0.0.1:44866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-04-23T02:08:05.521822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.541491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T02:08:05.521903Z","caller":"traceutil/trace.go:171","msg":"trace[296158064] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"308.645552ms","start":"2026-04-23T02:08:05.21325Z","end":"2026-04-23T02:08:05.521896Z","steps":["trace[296158064] 'agreement among raft nodes before linearized reading' (duration: 308.548832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T02:08:05.521961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T02:08:05.213239Z","time spent":"308.715603ms","remote":"127.0.0.1:44898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-23T02:08:05.521995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.08684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T02:08:05.522022Z","caller":"traceutil/trace.go:171","msg":"trace[1482512313] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:11; }","duration":"317.16676ms","start":"2026-04-23T02:08:05.204849Z","end":"2026-04-23T02:08:05.522016Z","steps":["trace[1482512313] 'agreement among raft nodes before linearized reading' (duration: 317.12457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T02:08:05.522042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T02:08:05.204827Z","time spent":"317.209211ms","remote":"127.0.0.1:44898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-04-23T02:08:08.729897Z","caller":"traceutil/trace.go:171","msg":"trace[1363269792] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"108.123704ms","start":"2026-04-23T02:08:08.62174Z","end":"2026-04-23T02:08:08.729863Z","steps":["trace[1363269792] 'process raft request' (duration: 57.335478ms)","trace[1363269792] 'compare' (duration: 50.656485ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T02:08:32.967747Z","caller":"traceutil/trace.go:171","msg":"trace[1362552887] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"128.173096ms","start":"2026-04-23T02:08:32.839555Z","end":"2026-04-23T02:08:32.967728Z","steps":["trace[1362552887] 'process raft request' (duration: 128.032456ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:08:32.977827Z","caller":"traceutil/trace.go:171","msg":"trace[797828233] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"127.270691ms","start":"2026-04-23T02:08:32.850539Z","end":"2026-04-23T02:08:32.97781Z","steps":["trace[797828233] 'process raft request' (duration: 126.998169ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:08:32.978224Z","caller":"traceutil/trace.go:171","msg":"trace[330735547] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"121.665543ms","start":"2026-04-23T02:08:32.856545Z","end":"2026-04-23T02:08:32.978211Z","steps":["trace[330735547] 'process raft request' (duration: 121.110449ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:08:32.979412Z","caller":"traceutil/trace.go:171","msg":"trace[1253064749] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"104.236696ms","start":"2026-04-23T02:08:32.875135Z","end":"2026-04-23T02:08:32.979372Z","steps":["trace[1253064749] 'process raft request' (duration: 103.702782ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:08:32.979537Z","caller":"traceutil/trace.go:171","msg":"trace[1867744882] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"106.40962ms","start":"2026-04-23T02:08:32.873117Z","end":"2026-04-23T02:08:32.979527Z","steps":["trace[1867744882] 'process raft request' (duration: 105.640764ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:08:33.152627Z","caller":"traceutil/trace.go:171","msg":"trace[309062433] transaction","detail":"{read_only:false; number_of_response:0; response_revision:525; }","duration":"136.938073ms","start":"2026-04-23T02:08:33.015665Z","end":"2026-04-23T02:08:33.152604Z","steps":["trace[309062433] 'process raft request' (duration: 136.911763ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:08:33.152836Z","caller":"traceutil/trace.go:171","msg":"trace[1926101315] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"137.538927ms","start":"2026-04-23T02:08:33.015279Z","end":"2026-04-23T02:08:33.152818Z","steps":["trace[1926101315] 'process raft request' (duration: 137.202495ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:08:33.152963Z","caller":"traceutil/trace.go:171","msg":"trace[670498888] transaction","detail":"{read_only:false; number_of_response:0; response_revision:525; }","duration":"137.403506ms","start":"2026-04-23T02:08:33.015546Z","end":"2026-04-23T02:08:33.15295Z","steps":["trace[670498888] 'process raft request' (duration: 136.992834ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:08:33.152965Z","caller":"traceutil/trace.go:171","msg":"trace[1462142880] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"140.842489ms","start":"2026-04-23T02:08:33.012111Z","end":"2026-04-23T02:08:33.152954Z","steps":["trace[1462142880] 'process raft request' (duration: 114.636613ms)","trace[1462142880] 'compare' (duration: 25.596782ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T02:08:33.153155Z","caller":"traceutil/trace.go:171","msg":"trace[1561133028] transaction","detail":"{read_only:false; number_of_response:0; response_revision:525; }","duration":"137.533848ms","start":"2026-04-23T02:08:33.015609Z","end":"2026-04-23T02:08:33.153143Z","steps":["trace[1561133028] 'process raft request' (duration: 136.950454ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:08:33.153243Z","caller":"traceutil/trace.go:171","msg":"trace[652256563] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"124.604671ms","start":"2026-04-23T02:08:33.028628Z","end":"2026-04-23T02:08:33.153233Z","steps":["trace[652256563] 'process raft request' (duration: 124.268398ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:08:33.153395Z","caller":"traceutil/trace.go:171","msg":"trace[58952801] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:540; }","duration":"125.172104ms","start":"2026-04-23T02:08:33.028196Z","end":"2026-04-23T02:08:33.153368Z","steps":["trace[58952801] 'read index received' (duration: 98.643065ms)","trace[58952801] 'applied index is now lower than readState.Index' (duration: 26.528009ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T02:08:33.153386Z","caller":"traceutil/trace.go:171","msg":"trace[1486943623] transaction","detail":"{read_only:false; number_of_response:0; response_revision:525; }","duration":"137.479007ms","start":"2026-04-23T02:08:33.015877Z","end":"2026-04-23T02:08:33.153356Z","steps":["trace[1486943623] 'process raft request' (duration: 136.933792ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:08:33.153495Z","caller":"traceutil/trace.go:171","msg":"trace[1385702647] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"116.986519ms","start":"2026-04-23T02:08:33.036498Z","end":"2026-04-23T02:08:33.153485Z","steps":["trace[1385702647] 'process raft request' (duration: 116.642176ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:08:33.15351Z","caller":"traceutil/trace.go:171","msg":"trace[1920111346] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"121.157588ms","start":"2026-04-23T02:08:33.03234Z","end":"2026-04-23T02:08:33.153498Z","steps":["trace[1920111346] 'process raft request' (duration: 120.656404ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:08:33.153562Z","caller":"traceutil/trace.go:171","msg":"trace[111096672] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"112.668851ms","start":"2026-04-23T02:08:33.040876Z","end":"2026-04-23T02:08:33.153545Z","steps":["trace[111096672] 'process raft request' (duration: 112.399189ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:08:33.153626Z","caller":"traceutil/trace.go:171","msg":"trace[127926538] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"121.219108ms","start":"2026-04-23T02:08:33.032395Z","end":"2026-04-23T02:08:33.153614Z","steps":["trace[127926538] 'process raft request' (duration: 120.670615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T02:08:33.153771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.553926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-23T02:08:33.153829Z","caller":"traceutil/trace.go:171","msg":"trace[435921651] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:530; }","duration":"125.642027ms","start":"2026-04-23T02:08:33.028174Z","end":"2026-04-23T02:08:33.153816Z","steps":["trace[435921651] 'agreement among raft nodes before linearized reading' (duration: 125.491856ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:08:40.066305Z","caller":"traceutil/trace.go:171","msg":"trace[1311181286] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"392.893149ms","start":"2026-04-23T02:08:39.67338Z","end":"2026-04-23T02:08:40.066274Z","steps":["trace[1311181286] 'process raft request' (duration: 392.700282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T02:08:40.066499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T02:08:39.673369Z","time spent":"393.044244ms","remote":"127.0.0.1:44960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T02:08:40.453911Z","caller":"traceutil/trace.go:171","msg":"trace[1969850557] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:638; }","duration":"614.403649ms","start":"2026-04-23T02:08:39.839486Z","end":"2026-04-23T02:08:40.45389Z","steps":["trace[1969850557] 'read index received' (duration: 226.626415ms)","trace[1969850557] 'applied index is now lower than readState.Index' (duration: 387.776284ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T02:08:40.45398Z","caller":"traceutil/trace.go:171","msg":"trace[1963342799] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"727.113338ms","start":"2026-04-23T02:08:39.726834Z","end":"2026-04-23T02:08:40.453947Z","steps":["trace[1963342799] 'process raft request' (duration: 705.140185ms)","trace[1963342799] 'compare' (duration: 21.554248ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T02:08:40.454123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.623406ms","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-04-23T02:08:40.454164Z","caller":"traceutil/trace.go:171","msg":"trace[400297317] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:617; }","duration":"614.71416ms","start":"2026-04-23T02:08:39.839439Z","end":"2026-04-23T02:08:40.454153Z","steps":["trace[400297317] 'agreement among raft nodes before linearized reading' (duration: 614.575295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T02:08:40.454196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T02:08:39.839422Z","time spent":"614.764891ms","remote":"127.0.0.1:45012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T02:08:40.454419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.855528ms","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-04-23T02:08:40.454463Z","caller":"traceutil/trace.go:171","msg":"trace[638988973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:617; }","duration":"462.93753ms","start":"2026-04-23T02:08:39.991515Z","end":"2026-04-23T02:08:40.454452Z","steps":["trace[638988973] 'agreement among raft nodes before linearized reading' (duration: 462.662531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T02:08:40.454491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T02:08:39.991498Z","time spent":"462.985981ms","remote":"127.0.0.1:45012","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-04-23T02:08:40.454508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T02:08:39.726806Z","time spent":"727.247802ms","remote":"127.0.0.1:44880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T02:08:40.454659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.754717ms","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-04-23T02:08:40.454697Z","caller":"traceutil/trace.go:171","msg":"trace[1634301852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:617; }","duration":"413.816188ms","start":"2026-04-23T02:08:40.040871Z","end":"2026-04-23T02:08:40.454687Z","steps":["trace[1634301852] 'agreement among raft nodes before linearized reading' (duration: 413.727445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T02:08:40.454723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T02:08:40.040859Z","time spent":"413.85755ms","remote":"127.0.0.1:45012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-23T02:08:40.666382Z","caller":"traceutil/trace.go:171","msg":"trace[494764000] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"206.858663ms","start":"2026-04-23T02:08:40.459493Z","end":"2026-04-23T02:08:40.666352Z","steps":["trace[494764000] 'process raft request' (duration: 203.747352ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:08:40.681666Z","caller":"traceutil/trace.go:171","msg":"trace[1261082449] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"177.597883ms","start":"2026-04-23T02:08:40.50404Z","end":"2026-04-23T02:08:40.681638Z","steps":["trace[1261082449] 'process raft request' (duration: 177.570682ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:08:40.681687Z","caller":"traceutil/trace.go:171","msg":"trace[1149321747] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"222.067097ms","start":"2026-04-23T02:08:40.459598Z","end":"2026-04-23T02:08:40.681666Z","steps":["trace[1149321747] 'process raft request' (duration: 221.896701ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:08:40.681912Z","caller":"traceutil/trace.go:171","msg":"trace[1196948410] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"221.682293ms","start":"2026-04-23T02:08:40.460219Z","end":"2026-04-23T02:08:40.681901Z","steps":["trace[1196948410] 'process raft request' (duration: 221.364562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T02:09:02.286392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.005112ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-23T02:09:34.102785Z","caller":"traceutil/trace.go:171","msg":"trace[883406711] linearizableReadLoop","detail":"{readStateIndex:1241; appliedIndex:1240; }","duration":"100.447409ms","start":"2026-04-23T02:09:34.002314Z","end":"2026-04-23T02:09:34.102761Z","steps":["trace[883406711] 'read index received' (duration: 100.277324ms)","trace[883406711] 'applied index is now lower than readState.Index' (duration: 169.275µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T02:09:34.102936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.608523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"info","ts":"2026-04-23T02:09:34.102977Z","caller":"traceutil/trace.go:171","msg":"trace[594997434] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:1204; }","duration":"100.697054ms","start":"2026-04-23T02:09:34.002269Z","end":"2026-04-23T02:09:34.102967Z","steps":["trace[594997434] 'agreement among raft nodes before linearized reading' (duration: 100.587251ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:09:34.103112Z","caller":"traceutil/trace.go:171","msg":"trace[589570019] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"177.104769ms","start":"2026-04-23T02:09:33.925971Z","end":"2026-04-23T02:09:34.103076Z","steps":["trace[589570019] 'process raft request' (duration: 176.634789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T02:10:19.980913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.407072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-04-23T02:10:19.980981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.910691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-23T02:10:19.981008Z","caller":"traceutil/trace.go:171","msg":"trace[1279450241] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1875; }","duration":"252.554253ms","start":"2026-04-23T02:10:19.728432Z","end":"2026-04-23T02:10:19.980986Z","steps":["trace[1279450241] 'range keys from in-memory index tree' (duration: 252.226649ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:10:19.981069Z","caller":"traceutil/trace.go:171","msg":"trace[993933115] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1875; }","duration":"262.060213ms","start":"2026-04-23T02:10:19.718986Z","end":"2026-04-23T02:10:19.981046Z","steps":["trace[993933115] 'range keys from in-memory index tree' (duration: 261.693718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T02:10:19.981144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.15154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5842bb31-93cc-4b8b-beab-ad505fc1b115\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-23T02:10:19.981244Z","caller":"traceutil/trace.go:171","msg":"trace[1617332719] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5842bb31-93cc-4b8b-beab-ad505fc1b115; range_end:; response_count:1; response_revision:1875; }","duration":"246.378993ms","start":"2026-04-23T02:10:19.734844Z","end":"2026-04-23T02:10:19.981223Z","steps":["trace[1617332719] 'range keys from in-memory index tree' (duration: 245.947277ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:10:20.348492Z","caller":"traceutil/trace.go:171","msg":"trace[1870527325] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"216.685998ms","start":"2026-04-23T02:10:20.131778Z","end":"2026-04-23T02:10:20.348464Z","steps":["trace[1870527325] 'process raft request' (duration: 216.527146ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:10:36.57892Z","caller":"traceutil/trace.go:171","msg":"trace[2076072447] transaction","detail":"{read_only:false; response_revision:2010; number_of_response:1; }","duration":"129.736387ms","start":"2026-04-23T02:10:36.449166Z","end":"2026-04-23T02:10:36.578902Z","steps":["trace[2076072447] 'process raft request' (duration: 129.626695ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:10:36.837904Z","caller":"traceutil/trace.go:171","msg":"trace[1907848178] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"179.758955ms","start":"2026-04-23T02:10:36.658122Z","end":"2026-04-23T02:10:36.837881Z","steps":["trace[1907848178] 'process raft request' (duration: 179.679324ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:10:36.837904Z","caller":"traceutil/trace.go:171","msg":"trace[214804395] transaction","detail":"{read_only:false; response_revision:2012; number_of_response:1; }","duration":"209.28542ms","start":"2026-04-23T02:10:36.628587Z","end":"2026-04-23T02:10:36.837872Z","steps":["trace[214804395] 'process raft request' (duration: 157.883093ms)","trace[214804395] 'compare' (duration: 51.213035ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T02:11:00.381508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.283279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-23T02:11:00.381604Z","caller":"traceutil/trace.go:171","msg":"trace[697010219] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2146; }","duration":"109.429872ms","start":"2026-04-23T02:11:00.272152Z","end":"2026-04-23T02:11:00.381582Z","steps":["trace[697010219] 'range keys from in-memory index tree' (duration: 109.068395ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:12:22.709911Z","caller":"traceutil/trace.go:171","msg":"trace[370077226] transaction","detail":"{read_only:false; response_revision:2702; number_of_response:1; }","duration":"102.666635ms","start":"2026-04-23T02:12:22.607221Z","end":"2026-04-23T02:12:22.709888Z","steps":["trace[370077226] 'process raft request' (duration: 102.318792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T02:12:22.921333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.945198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T02:12:22.921634Z","caller":"traceutil/trace.go:171","msg":"trace[1293803499] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:2702; }","duration":"116.07438ms","start":"2026-04-23T02:12:22.80532Z","end":"2026-04-23T02:12:22.921394Z","steps":["trace[1293803499] 'count revisions from in-memory index tree' (duration: 115.883258ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:16:04.471439Z","caller":"traceutil/trace.go:171","msg":"trace[959797503] transaction","detail":"{read_only:false; response_revision:4647; number_of_response:1; }","duration":"124.316556ms","start":"2026-04-23T02:16:04.347088Z","end":"2026-04-23T02:16:04.471405Z","steps":["trace[959797503] 'process raft request' (duration: 124.116362ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:16:04.989786Z","caller":"traceutil/trace.go:171","msg":"trace[1788906919] transaction","detail":"{read_only:false; response_revision:4654; number_of_response:1; }","duration":"159.949759ms","start":"2026-04-23T02:16:04.829811Z","end":"2026-04-23T02:16:04.989761Z","steps":["trace[1788906919] 'process raft request' (duration: 159.672335ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:16:04.990101Z","caller":"traceutil/trace.go:171","msg":"trace[2014432713] transaction","detail":"{read_only:false; response_revision:4655; number_of_response:1; }","duration":"160.137812ms","start":"2026-04-23T02:16:04.829935Z","end":"2026-04-23T02:16:04.990073Z","steps":["trace[2014432713] 'process raft request' (duration: 159.764156ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:16:04.990168Z","caller":"traceutil/trace.go:171","msg":"trace[69513210] transaction","detail":"{read_only:false; response_revision:4656; number_of_response:1; }","duration":"160.188844ms","start":"2026-04-23T02:16:04.829962Z","end":"2026-04-23T02:16:04.990151Z","steps":["trace[69513210] 'process raft request' (duration: 159.997201ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:16:04.990157Z","caller":"traceutil/trace.go:171","msg":"trace[459843353] transaction","detail":"{read_only:false; response_revision:4657; number_of_response:1; }","duration":"157.168332ms","start":"2026-04-23T02:16:04.832963Z","end":"2026-04-23T02:16:04.990131Z","steps":["trace[459843353] 'process raft request' (duration: 157.094201ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:16:17.116321Z","caller":"traceutil/trace.go:171","msg":"trace[1243044528] linearizableReadLoop","detail":"{readStateIndex:4998; appliedIndex:4997; }","duration":"267.442647ms","start":"2026-04-23T02:16:16.848851Z","end":"2026-04-23T02:16:17.116293Z","steps":["trace[1243044528] 'read index received' (duration: 267.150893ms)","trace[1243044528] 'applied index is now lower than readState.Index' (duration: 290.624µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T02:16:17.116425Z","caller":"traceutil/trace.go:171","msg":"trace[1449642608] transaction","detail":"{read_only:false; response_revision:4816; number_of_response:1; }","duration":"267.972007ms","start":"2026-04-23T02:16:16.848419Z","end":"2026-04-23T02:16:17.116391Z","steps":["trace[1449642608] 'process raft request' (duration: 267.704843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T02:16:17.116529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.649091ms","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-04-23T02:16:17.116772Z","caller":"traceutil/trace.go:171","msg":"trace[1689315132] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4816; }","duration":"267.917955ms","start":"2026-04-23T02:16:16.848828Z","end":"2026-04-23T02:16:17.116746Z","steps":["trace[1689315132] 'agreement among raft nodes before linearized reading' (duration: 267.570769ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:16:17.255627Z","caller":"traceutil/trace.go:171","msg":"trace[1065501160] transaction","detail":"{read_only:false; response_revision:4817; number_of_response:1; }","duration":"120.410802ms","start":"2026-04-23T02:16:17.135188Z","end":"2026-04-23T02:16:17.255599Z","steps":["trace[1065501160] 'process raft request' (duration: 70.699363ms)","trace[1065501160] 'compare' (duration: 49.314582ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T02:16:17.494412Z","caller":"traceutil/trace.go:171","msg":"trace[1039358000] linearizableReadLoop","detail":"{readStateIndex:5007; appliedIndex:5006; }","duration":"118.157005ms","start":"2026-04-23T02:16:17.376186Z","end":"2026-04-23T02:16:17.494343Z","steps":["trace[1039358000] 'read index received' (duration: 29.77571ms)","trace[1039358000] 'applied index is now lower than readState.Index' (duration: 88.380445ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T02:16:17.49447Z","caller":"traceutil/trace.go:171","msg":"trace[1755444983] transaction","detail":"{read_only:false; response_revision:4825; number_of_response:1; }","duration":"160.588183ms","start":"2026-04-23T02:16:17.333846Z","end":"2026-04-23T02:16:17.494434Z","steps":["trace[1755444983] 'process raft request' (duration: 72.077387ms)","trace[1755444983] 'compare' (duration: 88.285942ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T02:16:17.494698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.337347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T02:16:17.494736Z","caller":"traceutil/trace.go:171","msg":"trace[654066092] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4825; }","duration":"118.606982ms","start":"2026-04-23T02:16:17.376118Z","end":"2026-04-23T02:16:17.494725Z","steps":["trace[654066092] 'agreement among raft nodes before linearized reading' (duration: 118.347978ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T02:18:03.831013Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2919} {"level":"info","ts":"2026-04-23T02:18:03.923583Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2919,"took":"90.56383ms","hash":1944887590,"current-db-size-bytes":54296576,"current-db-size":"54 MB","current-db-size-in-use-bytes":29421568,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-23T02:18:03.923657Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1944887590,"revision":2919,"compact-revision":-1} {"level":"info","ts":"2026-04-23T02:20:06.864871Z","caller":"traceutil/trace.go:171","msg":"trace[585388256] transaction","detail":"{read_only:false; response_revision:6518; number_of_response:1; }","duration":"123.473659ms","start":"2026-04-23T02:20:06.741379Z","end":"2026-04-23T02:20:06.864853Z","steps":["trace[585388256] 'process raft request' (duration: 55.578535ms)","trace[585388256] 'compare' (duration: 67.765852ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T02:23:03.83661Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5635} {"level":"info","ts":"2026-04-23T02:23:03.909617Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5635,"took":"71.542031ms","hash":27051345,"current-db-size-bytes":54296576,"current-db-size":"54 MB","current-db-size-in-use-bytes":18239488,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-23T02:23:03.90967Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":27051345,"revision":5635,"compact-revision":2919}