{"level":"warn","ts":"2026-03-13T09:51:43.298699Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-13T09:51:43.29877Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.232: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.19.213.232:2380","--initial-cluster=instance=https://199.19.213.232:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.232:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.232: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-03-13T09:51:43.298822Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-13T09:51:43.298836Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.232:2380"]} {"level":"info","ts":"2026-03-13T09:51:43.298856Z","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-03-13T09:51:43.299657Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.232:2379"]} {"level":"info","ts":"2026-03-13T09:51:43.299736Z","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.19.213.232:2380"],"listen-peer-urls":["https://199.19.213.232:2380"],"advertise-client-urls":["https://199.19.213.232:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.232:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.232: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-03-13T09:51:43.303618Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.635977ms"} {"level":"info","ts":"2026-03-13T09:51:43.30995Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"20a2700e013b1fa5","cluster-id":"b7b35e617ebc7ac7"} {"level":"info","ts":"2026-03-13T09:51:43.31004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 switched to configuration voters=()"} {"level":"info","ts":"2026-03-13T09:51:43.310063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 became follower at term 0"} {"level":"info","ts":"2026-03-13T09:51:43.310076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 20a2700e013b1fa5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-13T09:51:43.310084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 became follower at term 1"} {"level":"info","ts":"2026-03-13T09:51:43.310163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 switched to configuration voters=(2351565160893325221)"} {"level":"warn","ts":"2026-03-13T09:51:43.316871Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-13T09:51:43.319337Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-13T09:51:43.320578Z","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-03-13T09:51:43.323062Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"20a2700e013b1fa5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-13T09:51:43.323189Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"20a2700e013b1fa5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-13T09:51:43.32352Z","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-03-13T09:51:43.323626Z","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-03-13T09:51:43.323645Z","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-03-13T09:51:43.325003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 switched to configuration voters=(2351565160893325221)"} {"level":"info","ts":"2026-03-13T09:51:43.325485Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b7b35e617ebc7ac7","local-member-id":"20a2700e013b1fa5","added-peer-id":"20a2700e013b1fa5","added-peer-peer-urls":["https://199.19.213.232:2380"]} {"level":"info","ts":"2026-03-13T09:51:43.326868Z","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-03-13T09:51:43.326988Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.232:2380"} {"level":"info","ts":"2026-03-13T09:51:43.327062Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"20a2700e013b1fa5","initial-advertise-peer-urls":["https://199.19.213.232:2380"],"listen-peer-urls":["https://199.19.213.232:2380"],"advertise-client-urls":["https://199.19.213.232:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.232:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-13T09:51:43.327101Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-13T09:51:43.327078Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.232:2380"} {"level":"info","ts":"2026-03-13T09:51:43.510633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 is starting a new election at term 1"} {"level":"info","ts":"2026-03-13T09:51:43.510708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-13T09:51:43.510757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 received MsgPreVoteResp from 20a2700e013b1fa5 at term 1"} {"level":"info","ts":"2026-03-13T09:51:43.510788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 became candidate at term 2"} {"level":"info","ts":"2026-03-13T09:51:43.51081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 received MsgVoteResp from 20a2700e013b1fa5 at term 2"} {"level":"info","ts":"2026-03-13T09:51:43.510847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 became leader at term 2"} {"level":"info","ts":"2026-03-13T09:51:43.510883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 20a2700e013b1fa5 elected leader 20a2700e013b1fa5 at term 2"} {"level":"info","ts":"2026-03-13T09:51:43.512275Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T09:51:43.513157Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"20a2700e013b1fa5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.232:2379]}","request-path":"/0/members/20a2700e013b1fa5/attributes","cluster-id":"b7b35e617ebc7ac7","publish-timeout":"7s"} {"level":"info","ts":"2026-03-13T09:51:43.513179Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T09:51:43.51329Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T09:51:43.513519Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-13T09:51:43.513602Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-13T09:51:43.513638Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b7b35e617ebc7ac7","local-member-id":"20a2700e013b1fa5","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T09:51:43.513777Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T09:51:43.513811Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T09:51:43.516364Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-13T09:51:43.517188Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.232:2379"} {"level":"warn","ts":"2026-03-13T09:51:46.528136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.37933ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:51:46.52851Z","caller":"traceutil/trace.go:171","msg":"trace[853177000] transaction","detail":"{read_only:false; response_revision:179; number_of_response:1; }","duration":"301.189547ms","start":"2026-03-13T09:51:46.227273Z","end":"2026-03-13T09:51:46.528462Z","steps":["trace[853177000] 'process raft request' (duration: 50.951247ms)","trace[853177000] 'compare' (duration: 249.244227ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:51:46.528606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:51:46.227259Z","time spent":"301.315271ms","remote":"127.0.0.1:44918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":768,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-13T09:51:46.720437Z","caller":"traceutil/trace.go:171","msg":"trace[950607334] transaction","detail":"{read_only:false; response_revision:181; number_of_response:1; }","duration":"186.231001ms","start":"2026-03-13T09:51:46.534183Z","end":"2026-03-13T09:51:46.720414Z","steps":["trace[950607334] 'process raft request' (duration: 186.18222ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:51:46.720611Z","caller":"traceutil/trace.go:171","msg":"trace[14390232] transaction","detail":"{read_only:false; response_revision:180; number_of_response:1; }","duration":"186.476927ms","start":"2026-03-13T09:51:46.534116Z","end":"2026-03-13T09:51:46.720593Z","steps":["trace[14390232] 'process raft request' (duration: 184.256814ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:52:21.978238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.545102ms","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-03-13T09:52:21.978323Z","caller":"traceutil/trace.go:171","msg":"trace[775971602] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:533; }","duration":"123.676075ms","start":"2026-03-13T09:52:21.854629Z","end":"2026-03-13T09:52:21.978305Z","steps":["trace[775971602] 'range keys from in-memory index tree' (duration: 123.130233ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:52:22.573079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.245434ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T09:52:22.573157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.302972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9sp7q\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-13T09:52:22.573202Z","caller":"traceutil/trace.go:171","msg":"trace[801684986] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:534; }","duration":"138.393207ms","start":"2026-03-13T09:52:22.43479Z","end":"2026-03-13T09:52:22.573183Z","steps":["trace[801684986] 'range keys from in-memory index tree' (duration: 138.220953ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:52:22.573297Z","caller":"traceutil/trace.go:171","msg":"trace[1188350023] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9sp7q; range_end:; response_count:1; response_revision:534; }","duration":"273.511877ms","start":"2026-03-13T09:52:22.299765Z","end":"2026-03-13T09:52:22.573277Z","steps":["trace[1188350023] 'range keys from in-memory index tree' (duration: 273.068526ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:52:22.573508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.511562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-13T09:52:22.57354Z","caller":"traceutil/trace.go:171","msg":"trace[1565279295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:534; }","duration":"223.545243ms","start":"2026-03-13T09:52:22.349985Z","end":"2026-03-13T09:52:22.57353Z","steps":["trace[1565279295] 'range keys from in-memory index tree' (duration: 223.371318ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:52:22.573149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.495265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:52:22.57374Z","caller":"traceutil/trace.go:171","msg":"trace[2061846104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"448.123409ms","start":"2026-03-13T09:52:22.125564Z","end":"2026-03-13T09:52:22.573688Z","steps":["trace[2061846104] 'range keys from in-memory index tree' (duration: 447.416643ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:52:22.573805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:52:22.125545Z","time spent":"448.247473ms","remote":"127.0.0.1:44598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-13T09:52:22.604026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.344575ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-13T09:52:41.975451Z","caller":"traceutil/trace.go:171","msg":"trace[1557162742] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"102.344596ms","start":"2026-03-13T09:52:41.873085Z","end":"2026-03-13T09:52:41.97543Z","steps":["trace[1557162742] 'process raft request' (duration: 102.212453ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:52:42.430915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.602474ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:52:42.431041Z","caller":"traceutil/trace.go:171","msg":"trace[1800623726] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:741; }","duration":"313.744148ms","start":"2026-03-13T09:52:42.11728Z","end":"2026-03-13T09:52:42.431024Z","steps":["trace[1800623726] 'read index received' (duration: 212.629942ms)","trace[1800623726] 'applied index is now lower than readState.Index' (duration: 101.112536ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:52:42.431103Z","caller":"traceutil/trace.go:171","msg":"trace[382642746] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"370.231058ms","start":"2026-03-13T09:52:42.060836Z","end":"2026-03-13T09:52:42.431067Z","steps":["trace[382642746] 'process raft request' (duration: 269.124482ms)","trace[382642746] 'compare' (duration: 100.4868ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:52:42.431189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.905502ms","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-03-13T09:52:42.431221Z","caller":"traceutil/trace.go:171","msg":"trace[391314381] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:719; }","duration":"313.975523ms","start":"2026-03-13T09:52:42.117236Z","end":"2026-03-13T09:52:42.431211Z","steps":["trace[391314381] 'agreement among raft nodes before linearized reading' (duration: 313.83522ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:52:42.431257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:52:42.117215Z","time spent":"314.034074ms","remote":"127.0.0.1:44830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-13T09:52:42.431268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:52:42.060819Z","time spent":"370.356201ms","remote":"127.0.0.1:44830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:52:42.560487Z","caller":"traceutil/trace.go:171","msg":"trace[1731258610] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"124.413404ms","start":"2026-03-13T09:52:42.436053Z","end":"2026-03-13T09:52:42.560466Z","steps":["trace[1731258610] 'process raft request' (duration: 124.311722ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:53:11.047649Z","caller":"traceutil/trace.go:171","msg":"trace[956651641] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"123.545927ms","start":"2026-03-13T09:53:10.924073Z","end":"2026-03-13T09:53:11.047619Z","steps":["trace[956651641] 'process raft request' (duration: 123.390493ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:53:11.562434Z","caller":"traceutil/trace.go:171","msg":"trace[52757991] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"134.431906ms","start":"2026-03-13T09:53:11.427974Z","end":"2026-03-13T09:53:11.562406Z","steps":["trace[52757991] 'process raft request' (duration: 134.247812ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:53:12.2719Z","caller":"traceutil/trace.go:171","msg":"trace[464714640] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"126.683542ms","start":"2026-03-13T09:53:12.145193Z","end":"2026-03-13T09:53:12.271877Z","steps":["trace[464714640] 'process raft request' (duration: 126.515748ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:53:13.2434Z","caller":"traceutil/trace.go:171","msg":"trace[1731128762] linearizableReadLoop","detail":"{readStateIndex:842; appliedIndex:841; }","duration":"118.541069ms","start":"2026-03-13T09:53:13.124807Z","end":"2026-03-13T09:53:13.243349Z","steps":["trace[1731128762] 'read index received' (duration: 118.275402ms)","trace[1731128762] 'applied index is now lower than readState.Index' (duration: 264.587µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:53:13.24368Z","caller":"traceutil/trace.go:171","msg":"trace[2100993974] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"185.860108ms","start":"2026-03-13T09:53:13.057693Z","end":"2026-03-13T09:53:13.243553Z","steps":["trace[2100993974] 'process raft request' (duration: 185.472749ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:53:13.243716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.862936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:53:13.243859Z","caller":"traceutil/trace.go:171","msg":"trace[1385144135] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:811; }","duration":"119.05078ms","start":"2026-03-13T09:53:13.124772Z","end":"2026-03-13T09:53:13.243823Z","steps":["trace[1385144135] 'agreement among raft nodes before linearized reading' (duration: 118.778543ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:53:51.55987Z","caller":"traceutil/trace.go:171","msg":"trace[939520876] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"150.934677ms","start":"2026-03-13T09:53:51.408911Z","end":"2026-03-13T09:53:51.559846Z","steps":["trace[939520876] 'process raft request' (duration: 131.041524ms)","trace[939520876] 'compare' (duration: 19.516874ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:53:51.559918Z","caller":"traceutil/trace.go:171","msg":"trace[2139804437] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1037; }","duration":"118.412325ms","start":"2026-03-13T09:53:51.441473Z","end":"2026-03-13T09:53:51.559885Z","steps":["trace[2139804437] 'read index received' (duration: 98.485381ms)","trace[2139804437] 'applied index is now lower than readState.Index' (duration: 19.925314ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:53:51.560243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.747303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:53:51.560513Z","caller":"traceutil/trace.go:171","msg":"trace[909763173] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:999; }","duration":"119.04412ms","start":"2026-03-13T09:53:51.441444Z","end":"2026-03-13T09:53:51.560488Z","steps":["trace[909763173] 'agreement among raft nodes before linearized reading' (duration: 118.549867ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:53:56.743877Z","caller":"traceutil/trace.go:171","msg":"trace[1815149714] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"160.727766ms","start":"2026-03-13T09:53:56.583127Z","end":"2026-03-13T09:53:56.743854Z","steps":["trace[1815149714] 'process raft request' (duration: 122.859746ms)","trace[1815149714] 'compare' (duration: 37.766128ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:53:57.889005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.170431ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:53:57.889338Z","caller":"traceutil/trace.go:171","msg":"trace[1553595826] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"164.078987ms","start":"2026-03-13T09:53:57.725212Z","end":"2026-03-13T09:53:57.889291Z","steps":["trace[1553595826] 'process raft request' (duration: 36.543018ms)","trace[1553595826] 'compare' (duration: 127.025447ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:53:57.88939Z","caller":"traceutil/trace.go:171","msg":"trace[1541958055] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"107.174095ms","start":"2026-03-13T09:53:57.782189Z","end":"2026-03-13T09:53:57.889363Z","steps":["trace[1541958055] 'process raft request' (duration: 107.010372ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:53:58.076402Z","caller":"traceutil/trace.go:171","msg":"trace[1526016831] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"182.180916ms","start":"2026-03-13T09:53:57.894183Z","end":"2026-03-13T09:53:58.076363Z","steps":["trace[1526016831] 'process raft request' (duration: 160.052711ms)","trace[1526016831] 'compare' (duration: 21.764226ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:53:58.076532Z","caller":"traceutil/trace.go:171","msg":"trace[844076079] linearizableReadLoop","detail":"{readStateIndex:1078; appliedIndex:1076; }","duration":"181.47998ms","start":"2026-03-13T09:53:57.89503Z","end":"2026-03-13T09:53:58.07651Z","steps":["trace[844076079] 'read index received' (duration: 36.704991ms)","trace[844076079] 'applied index is now lower than readState.Index' (duration: 144.773659ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:53:58.076736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.688345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-03-13T09:53:58.076777Z","caller":"traceutil/trace.go:171","msg":"trace[49014188] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1037; }","duration":"181.778817ms","start":"2026-03-13T09:53:57.894985Z","end":"2026-03-13T09:53:58.076764Z","steps":["trace[49014188] 'agreement among raft nodes before linearized reading' (duration: 181.604003ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:53:58.076883Z","caller":"traceutil/trace.go:171","msg":"trace[406244460] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"180.853695ms","start":"2026-03-13T09:53:57.896021Z","end":"2026-03-13T09:53:58.076874Z","steps":["trace[406244460] 'process raft request' (duration: 180.107948ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:53:58.8215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.071196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:53:58.821933Z","caller":"traceutil/trace.go:171","msg":"trace[730217298] linearizableReadLoop","detail":"{readStateIndex:1085; appliedIndex:1083; }","duration":"246.549575ms","start":"2026-03-13T09:53:58.575362Z","end":"2026-03-13T09:53:58.821912Z","steps":["trace[730217298] 'read index received' (duration: 39.261µs)","trace[730217298] 'applied index is now lower than readState.Index' (duration: 246.509084ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:53:58.821965Z","caller":"traceutil/trace.go:171","msg":"trace[1545751306] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"403.195885ms","start":"2026-03-13T09:53:58.41873Z","end":"2026-03-13T09:53:58.821925Z","steps":["trace[1545751306] 'process raft request' (duration: 113.545806ms)","trace[1545751306] 'compare' (duration: 288.899841ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:53:58.822006Z","caller":"traceutil/trace.go:171","msg":"trace[391867598] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"390.162266ms","start":"2026-03-13T09:53:58.431819Z","end":"2026-03-13T09:53:58.821981Z","steps":["trace[391867598] 'process raft request' (duration: 390.004482ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:53:58.822168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.789401ms","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-03-13T09:53:58.822215Z","caller":"traceutil/trace.go:171","msg":"trace[1898392537] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1043; }","duration":"246.880703ms","start":"2026-03-13T09:53:58.575324Z","end":"2026-03-13T09:53:58.822205Z","steps":["trace[1898392537] 'agreement among raft nodes before linearized reading' (duration: 246.703449ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:53:58.822245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:53:58.431799Z","time spent":"390.355401ms","remote":"127.0.0.1:44672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":805,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T09:53:58.82214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:53:58.418712Z","time spent":"403.374179ms","remote":"127.0.0.1:44830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:53:59.161976Z","caller":"traceutil/trace.go:171","msg":"trace[1077691840] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"336.571072ms","start":"2026-03-13T09:53:58.825364Z","end":"2026-03-13T09:53:59.161936Z","steps":["trace[1077691840] 'process raft request' (duration: 273.585227ms)","trace[1077691840] 'compare' (duration: 62.597506ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:53:59.162137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:53:58.825339Z","time spent":"336.719326ms","remote":"127.0.0.1:44672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":855,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-13T09:53:59.162783Z","caller":"traceutil/trace.go:171","msg":"trace[1386292537] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"336.966642ms","start":"2026-03-13T09:53:58.825798Z","end":"2026-03-13T09:53:59.162765Z","steps":["trace[1386292537] 'process raft request' (duration: 336.556762ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:53:59.162777Z","caller":"traceutil/trace.go:171","msg":"trace[1492601825] linearizableReadLoop","detail":"{readStateIndex:1087; appliedIndex:1085; }","duration":"218.703184ms","start":"2026-03-13T09:53:58.944034Z","end":"2026-03-13T09:53:59.162737Z","steps":["trace[1492601825] 'read index received' (duration: 154.92529ms)","trace[1492601825] 'applied index is now lower than readState.Index' (duration: 63.771024ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:53:59.16289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:53:58.825782Z","time spent":"337.042623ms","remote":"127.0.0.1:44830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:53:59.163165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.090654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-13T09:53:59.16323Z","caller":"traceutil/trace.go:171","msg":"trace[2092446000] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1045; }","duration":"219.238187ms","start":"2026-03-13T09:53:58.943971Z","end":"2026-03-13T09:53:59.163209Z","steps":["trace[2092446000] 'agreement among raft nodes before linearized reading' (duration: 218.98905ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:53:59.163191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.031062ms","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-03-13T09:53:59.163373Z","caller":"traceutil/trace.go:171","msg":"trace[1062694884] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1045; }","duration":"179.243917ms","start":"2026-03-13T09:53:58.984105Z","end":"2026-03-13T09:53:59.163348Z","steps":["trace[1062694884] 'agreement among raft nodes before linearized reading' (duration: 178.92721ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:52.749845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.153792ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-13T09:55:52.749935Z","caller":"traceutil/trace.go:171","msg":"trace[1981083989] linearizableReadLoop","detail":"{readStateIndex:1580; appliedIndex:1579; }","duration":"113.763904ms","start":"2026-03-13T09:55:52.636156Z","end":"2026-03-13T09:55:52.74992Z","steps":["trace[1981083989] 'read index received' (duration: 10.52138ms)","trace[1981083989] 'applied index is now lower than readState.Index' (duration: 103.241104ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:55:52.750032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.868416ms","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-03-13T09:55:52.750054Z","caller":"traceutil/trace.go:171","msg":"trace[361046465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1515; }","duration":"113.923777ms","start":"2026-03-13T09:55:52.636124Z","end":"2026-03-13T09:55:52.750048Z","steps":["trace[361046465] 'agreement among raft nodes before linearized reading' (duration: 113.829025ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:53.89568Z","caller":"traceutil/trace.go:171","msg":"trace[1344876948] transaction","detail":"{read_only:false; response_revision:1520; number_of_response:1; }","duration":"104.374191ms","start":"2026-03-13T09:55:53.791276Z","end":"2026-03-13T09:55:53.89565Z","steps":["trace[1344876948] 'process raft request' (duration: 104.065204ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:55.602335Z","caller":"traceutil/trace.go:171","msg":"trace[1883041059] transaction","detail":"{read_only:false; response_revision:1526; number_of_response:1; }","duration":"103.613533ms","start":"2026-03-13T09:55:55.498656Z","end":"2026-03-13T09:55:55.60227Z","steps":["trace[1883041059] 'process raft request' (duration: 72.253436ms)","trace[1883041059] 'compare' (duration: 31.1022ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:55:56.252295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.376082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:55:56.252387Z","caller":"traceutil/trace.go:171","msg":"trace[970777723] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1530; }","duration":"128.498324ms","start":"2026-03-13T09:55:56.123869Z","end":"2026-03-13T09:55:56.252368Z","steps":["trace[970777723] 'range keys from in-memory index tree' (duration: 128.271429ms)"],"step_count":1}