{"level":"warn","ts":"2026-02-25T09:58:19.374164Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T09:58:19.37425Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.244: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.244:2380","--initial-cluster=instance=https://199.204.45.244:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.244:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.244: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-02-25T09:58:19.374357Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T09:58:19.374824Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-02-25T09:58:19.375073Z","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-02-25T09:58:19.376235Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"]} {"level":"info","ts":"2026-02-25T09:58:19.376351Z","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.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.244: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-02-25T09:58:19.408394Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"26.222344ms"} {"level":"info","ts":"2026-02-25T09:58:19.438137Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1686e9b1ea717edf","cluster-id":"71c60fe001ca5b3c"} {"level":"info","ts":"2026-02-25T09:58:19.438238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T09:58:19.438275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 0"} {"level":"info","ts":"2026-02-25T09:58:19.43837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1686e9b1ea717edf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T09:58:19.438394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 1"} {"level":"info","ts":"2026-02-25T09:58:19.43855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"warn","ts":"2026-02-25T09:58:19.445374Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T09:58:19.44673Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T09:58:19.44775Z","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-02-25T09:58:19.450039Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1686e9b1ea717edf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T09:58:19.450288Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1686e9b1ea717edf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T09:58:19.450454Z","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-02-25T09:58:19.452318Z","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-02-25T09:58:19.45237Z","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-02-25T09:58:19.453706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"info","ts":"2026-02-25T09:58:19.453958Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","added-peer-id":"1686e9b1ea717edf","added-peer-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-02-25T09:58:19.455069Z","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-02-25T09:58:19.455129Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-02-25T09:58:19.455241Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-02-25T09:58:19.455247Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1686e9b1ea717edf","initial-advertise-peer-urls":["https://199.204.45.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T09:58:19.455294Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T09:58:20.439129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T09:58:20.439176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T09:58:20.439204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgPreVoteResp from 1686e9b1ea717edf at term 1"} {"level":"info","ts":"2026-02-25T09:58:20.439217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became candidate at term 2"} {"level":"info","ts":"2026-02-25T09:58:20.439226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgVoteResp from 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-02-25T09:58:20.439237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became leader at term 2"} {"level":"info","ts":"2026-02-25T09:58:20.439245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1686e9b1ea717edf elected leader 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-02-25T09:58:20.440158Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1686e9b1ea717edf","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.244:2379]}","request-path":"/0/members/1686e9b1ea717edf/attributes","cluster-id":"71c60fe001ca5b3c","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T09:58:20.440167Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T09:58:20.440223Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T09:58:20.440307Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T09:58:20.440916Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T09:58:20.440971Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T09:58:20.441406Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T09:58:20.441526Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T09:58:20.441562Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T09:58:20.44239Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T09:58:20.444159Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.244:2379"} {"level":"warn","ts":"2026-02-25T09:58:22.245116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.790581ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-25T09:58:22.245545Z","caller":"traceutil/trace.go:171","msg":"trace[1441142999] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"544.705502ms","start":"2026-02-25T09:58:21.70064Z","end":"2026-02-25T09:58:22.245345Z","steps":["trace[1441142999] 'process raft request' (duration: 270.599729ms)","trace[1441142999] 'compare' (duration: 272.621889ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:58:22.245686Z","caller":"traceutil/trace.go:171","msg":"trace[189038500] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"544.934975ms","start":"2026-02-25T09:58:21.700661Z","end":"2026-02-25T09:58:22.245596Z","steps":["trace[189038500] 'process raft request' (duration: 544.591921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:58:22.245793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:58:21.700644Z","time spent":"545.110566ms","remote":"127.0.0.1:53822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T09:58:22.245828Z","caller":"traceutil/trace.go:171","msg":"trace[1026042944] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"544.418139ms","start":"2026-02-25T09:58:21.701382Z","end":"2026-02-25T09:58:22.2458Z","steps":["trace[1026042944] 'process raft request' (duration: 543.928035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:58:22.245842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:58:21.70063Z","time spent":"545.095986ms","remote":"127.0.0.1:53822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T09:58:22.245923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:58:21.701329Z","time spent":"544.547211ms","remote":"127.0.0.1:53822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T09:58:22.246362Z","caller":"traceutil/trace.go:171","msg":"trace[772471690] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:8; }","duration":"484.559051ms","start":"2026-02-25T09:58:21.761787Z","end":"2026-02-25T09:58:22.246346Z","steps":["trace[772471690] 'read index received' (duration: 209.493288ms)","trace[772471690] 'applied index is now lower than readState.Index' (duration: 275.064443ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:58:22.246461Z","caller":"traceutil/trace.go:171","msg":"trace[1578189050] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"526.989529ms","start":"2026-02-25T09:58:21.719458Z","end":"2026-02-25T09:58:22.246448Z","steps":["trace[1578189050] 'process raft request' (duration: 526.843078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:58:22.246541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:58:21.719436Z","time spent":"527.06291ms","remote":"127.0.0.1:53428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2849,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T09:58:22.24671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.337709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T09:58:22.246772Z","caller":"traceutil/trace.go:171","msg":"trace[178306240] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"546.406759ms","start":"2026-02-25T09:58:21.700345Z","end":"2026-02-25T09:58:22.246751Z","steps":["trace[178306240] 'agreement among raft nodes before linearized reading' (duration: 546.269588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:58:22.246768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.239417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T09:58:22.246777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.103115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T09:58:22.246808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:58:21.70034Z","time spent":"546.45845ms","remote":"127.0.0.1:53364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-02-25T09:58:22.24681Z","caller":"traceutil/trace.go:171","msg":"trace[177604778] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"548.283308ms","start":"2026-02-25T09:58:21.698515Z","end":"2026-02-25T09:58:22.246798Z","steps":["trace[177604778] 'agreement among raft nodes before linearized reading' (duration: 548.210007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:58:22.246796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.320748ms","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-02-25T09:58:22.246838Z","caller":"traceutil/trace.go:171","msg":"trace[1253864236] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"495.211386ms","start":"2026-02-25T09:58:21.751611Z","end":"2026-02-25T09:58:22.246822Z","steps":["trace[1253864236] 'agreement among raft nodes before linearized reading' (duration: 495.085775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:58:22.246848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:58:21.698471Z","time spent":"548.370619ms","remote":"127.0.0.1:53664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-02-25T09:58:22.24688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:58:21.751596Z","time spent":"495.276347ms","remote":"127.0.0.1:53364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-02-25T09:58:22.246886Z","caller":"traceutil/trace.go:171","msg":"trace[18725629] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"546.434579ms","start":"2026-02-25T09:58:21.700428Z","end":"2026-02-25T09:58:22.246862Z","steps":["trace[18725629] 'agreement among raft nodes before linearized reading' (duration: 546.157607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:58:22.246941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:58:21.700423Z","time spent":"546.50422ms","remote":"127.0.0.1:53324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-02-25T09:58:22.246985Z","caller":"traceutil/trace.go:171","msg":"trace[207089238] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"545.556121ms","start":"2026-02-25T09:58:21.701418Z","end":"2026-02-25T09:58:22.246974Z","steps":["trace[207089238] 'process raft request' (duration: 544.157797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:58:22.247048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:58:21.701407Z","time spent":"545.605872ms","remote":"127.0.0.1:53822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T09:58:22.247193Z","caller":"traceutil/trace.go:171","msg":"trace[796341281] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"545.719512ms","start":"2026-02-25T09:58:21.701455Z","end":"2026-02-25T09:58:22.247175Z","steps":["trace[796341281] 'process raft request' (duration: 544.781823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:58:22.247253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:58:21.701449Z","time spent":"545.770594ms","remote":"127.0.0.1:53822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T09:58:22.25778Z","caller":"traceutil/trace.go:171","msg":"trace[1733518715] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"492.351047ms","start":"2026-02-25T09:58:21.7654Z","end":"2026-02-25T09:58:22.257751Z","steps":["trace[1733518715] 'process raft request' (duration: 492.191746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:58:22.257946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:58:21.765335Z","time spent":"492.522489ms","remote":"127.0.0.1:53712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T09:58:22.258004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.961062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T09:58:22.258108Z","caller":"traceutil/trace.go:171","msg":"trace[771417342] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:12; }","duration":"378.106564ms","start":"2026-02-25T09:58:21.879977Z","end":"2026-02-25T09:58:22.258083Z","steps":["trace[771417342] 'agreement among raft nodes before linearized reading' (duration: 377.887572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:58:22.258151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:58:21.879956Z","time spent":"378.189145ms","remote":"127.0.0.1:53516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-02-25T09:58:22.258196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.76926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T09:58:22.258241Z","caller":"traceutil/trace.go:171","msg":"trace[1417311137] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:12; }","duration":"493.816271ms","start":"2026-02-25T09:58:21.764416Z","end":"2026-02-25T09:58:22.258232Z","steps":["trace[1417311137] 'agreement among raft nodes before linearized reading' (duration: 493.75063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:58:22.258267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:58:21.764384Z","time spent":"493.878553ms","remote":"127.0.0.1:53364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-02-25T09:58:22.548532Z","caller":"traceutil/trace.go:171","msg":"trace[345158082] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"296.135252ms","start":"2026-02-25T09:58:22.252364Z","end":"2026-02-25T09:58:22.548499Z","steps":["trace[345158082] 'process raft request' (duration: 210.087668ms)","trace[345158082] 'compare' (duration: 85.837172ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:58:22.54859Z","caller":"traceutil/trace.go:171","msg":"trace[2125270530] transaction","detail":"{read_only:false; number_of_response:0; response_revision:13; }","duration":"296.067692ms","start":"2026-02-25T09:58:22.25249Z","end":"2026-02-25T09:58:22.548558Z","steps":["trace[2125270530] 'process raft request' (duration: 295.95375ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:58:22.548697Z","caller":"traceutil/trace.go:171","msg":"trace[532481765] linearizableReadLoop","detail":"{readStateIndex:22; appliedIndex:15; }","duration":"290.948722ms","start":"2026-02-25T09:58:22.25772Z","end":"2026-02-25T09:58:22.548669Z","steps":["trace[532481765] 'read index received' (duration: 204.747326ms)","trace[532481765] 'applied index is now lower than readState.Index' (duration: 86.200276ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:58:22.54881Z","caller":"traceutil/trace.go:171","msg":"trace[2052346073] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"294.620509ms","start":"2026-02-25T09:58:22.25418Z","end":"2026-02-25T09:58:22.5488Z","steps":["trace[2052346073] 'process raft request' (duration: 294.389436ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:58:22.54881Z","caller":"traceutil/trace.go:171","msg":"trace[1946965385] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"294.809531ms","start":"2026-02-25T09:58:22.253986Z","end":"2026-02-25T09:58:22.548795Z","steps":["trace[1946965385] 'process raft request' (duration: 294.495137ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:58:22.548942Z","caller":"traceutil/trace.go:171","msg":"trace[107266156] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"294.73451ms","start":"2026-02-25T09:58:22.2542Z","end":"2026-02-25T09:58:22.548934Z","steps":["trace[107266156] 'process raft request' (duration: 294.403456ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:58:22.549017Z","caller":"traceutil/trace.go:171","msg":"trace[579680279] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"294.75514ms","start":"2026-02-25T09:58:22.254255Z","end":"2026-02-25T09:58:22.54901Z","steps":["trace[579680279] 'process raft request' (duration: 294.375975ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:58:22.548736Z","caller":"traceutil/trace.go:171","msg":"trace[337063422] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"294.715049ms","start":"2026-02-25T09:58:22.254014Z","end":"2026-02-25T09:58:22.548729Z","steps":["trace[337063422] 'process raft request' (duration: 294.521417ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:58:22.549275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.012551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:2861"} {"level":"info","ts":"2026-02-25T09:58:22.549338Z","caller":"traceutil/trace.go:171","msg":"trace[2083377475] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:18; }","duration":"297.131753ms","start":"2026-02-25T09:58:22.252189Z","end":"2026-02-25T09:58:22.549321Z","steps":["trace[2083377475] 'agreement among raft nodes before linearized reading' (duration: 296.88049ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:58:22.557706Z","caller":"traceutil/trace.go:171","msg":"trace[57260636] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"298.967991ms","start":"2026-02-25T09:58:22.258708Z","end":"2026-02-25T09:58:22.557676Z","steps":["trace[57260636] 'process raft request' (duration: 298.807479ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:58:22.557862Z","caller":"traceutil/trace.go:171","msg":"trace[1681561964] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"297.679648ms","start":"2026-02-25T09:58:22.260166Z","end":"2026-02-25T09:58:22.557846Z","steps":["trace[1681561964] 'process raft request' (duration: 297.445246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:58:22.558115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.775975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T09:58:22.558271Z","caller":"traceutil/trace.go:171","msg":"trace[721768348] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:20; }","duration":"245.938636ms","start":"2026-02-25T09:58:22.312313Z","end":"2026-02-25T09:58:22.558252Z","steps":["trace[721768348] 'agreement among raft nodes before linearized reading' (duration: 245.663174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:58:22.558161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.784244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-02-25T09:58:22.558168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.739773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-02-25T09:58:22.558487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.523469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-25T09:58:22.558642Z","caller":"traceutil/trace.go:171","msg":"trace[2116813949] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"122.718421ms","start":"2026-02-25T09:58:22.43591Z","end":"2026-02-25T09:58:22.558628Z","steps":["trace[2116813949] 'agreement among raft nodes before linearized reading' (duration: 121.953704ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:58:22.558502Z","caller":"traceutil/trace.go:171","msg":"trace[598842475] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"246.126738ms","start":"2026-02-25T09:58:22.312335Z","end":"2026-02-25T09:58:22.558461Z","steps":["trace[598842475] 'agreement among raft nodes before linearized reading' (duration: 245.767095ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:58:22.558569Z","caller":"traceutil/trace.go:171","msg":"trace[195554701] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"251.185697ms","start":"2026-02-25T09:58:22.307367Z","end":"2026-02-25T09:58:22.558553Z","steps":["trace[195554701] 'agreement among raft nodes before linearized reading' (duration: 250.641322ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:58:53.083062Z","caller":"traceutil/trace.go:171","msg":"trace[2097852480] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"124.473794ms","start":"2026-02-25T09:58:52.958568Z","end":"2026-02-25T09:58:53.083041Z","steps":["trace[2097852480] 'process raft request' (duration: 124.354354ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:58:55.456466Z","caller":"traceutil/trace.go:171","msg":"trace[2127542618] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"231.570571ms","start":"2026-02-25T09:58:55.224869Z","end":"2026-02-25T09:58:55.45644Z","steps":["trace[2127542618] 'process raft request' (duration: 231.42844ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:58:55.722142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.643294ms","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-02-25T09:58:55.722215Z","caller":"traceutil/trace.go:171","msg":"trace[1490480079] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:548; }","duration":"152.769786ms","start":"2026-02-25T09:58:55.56943Z","end":"2026-02-25T09:58:55.7222Z","steps":["trace[1490480079] 'range keys from in-memory index tree' (duration: 152.561924ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:58:56.100839Z","caller":"traceutil/trace.go:171","msg":"trace[1871494562] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:566; }","duration":"278.983916ms","start":"2026-02-25T09:58:55.821829Z","end":"2026-02-25T09:58:56.100813Z","steps":["trace[1871494562] 'read index received' (duration: 278.804085ms)","trace[1871494562] 'applied index is now lower than readState.Index' (duration: 178.791µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:58:56.100949Z","caller":"traceutil/trace.go:171","msg":"trace[669686669] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"350.73096ms","start":"2026-02-25T09:58:55.750182Z","end":"2026-02-25T09:58:56.100913Z","steps":["trace[669686669] 'process raft request' (duration: 350.478708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:58:56.100975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.141987ms","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-02-25T09:58:56.101158Z","caller":"traceutil/trace.go:171","msg":"trace[1761737447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:550; }","duration":"279.359749ms","start":"2026-02-25T09:58:55.821779Z","end":"2026-02-25T09:58:56.101139Z","steps":["trace[1761737447] 'agreement among raft nodes before linearized reading' (duration: 279.142008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:58:56.101302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:58:55.750169Z","time spent":"350.863661ms","remote":"127.0.0.1:53428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4347,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T09:58:56.113754Z","caller":"traceutil/trace.go:171","msg":"trace[1904464739] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"100.547277ms","start":"2026-02-25T09:58:56.01319Z","end":"2026-02-25T09:58:56.113737Z","steps":["trace[1904464739] 'process raft request' (duration: 100.445876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:58:56.113879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.961734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T09:58:56.113942Z","caller":"traceutil/trace.go:171","msg":"trace[1970581897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:551; }","duration":"216.072434ms","start":"2026-02-25T09:58:55.897854Z","end":"2026-02-25T09:58:56.113926Z","steps":["trace[1970581897] 'agreement among raft nodes before linearized reading' (duration: 215.948673ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:58:56.219449Z","caller":"traceutil/trace.go:171","msg":"trace[1372666525] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"102.229168ms","start":"2026-02-25T09:58:56.117196Z","end":"2026-02-25T09:58:56.219426Z","steps":["trace[1372666525] 'process raft request' (duration: 97.892537ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:58:56.712203Z","caller":"traceutil/trace.go:171","msg":"trace[734929143] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"126.32223ms","start":"2026-02-25T09:58:56.585861Z","end":"2026-02-25T09:58:56.712183Z","steps":["trace[734929143] 'process raft request' (duration: 126.184429ms)"],"step_count":1}