{"level":"warn","ts":"2026-04-17T18:41:01.159812Z","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-17T18:41:01.160042Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.229: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.229:2380","--initial-cluster=instance=https://199.204.45.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.229: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-17T18:41:01.160135Z","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-17T18:41:01.160149Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-04-17T18:41:01.160169Z","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-17T18:41:01.160867Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"]} {"level":"info","ts":"2026-04-17T18:41:01.160963Z","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.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.229: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-17T18:41:01.168468Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.291048ms"} {"level":"info","ts":"2026-04-17T18:41:01.180053Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3ba9ae53cc4335db","cluster-id":"4ad2674607ff1d60"} {"level":"info","ts":"2026-04-17T18:41:01.180146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=()"} {"level":"info","ts":"2026-04-17T18:41:01.180167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 0"} {"level":"info","ts":"2026-04-17T18:41:01.180181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3ba9ae53cc4335db [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-17T18:41:01.180199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 1"} {"level":"info","ts":"2026-04-17T18:41:01.180236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"warn","ts":"2026-04-17T18:41:01.196365Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-17T18:41:01.200583Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-17T18:41:01.202146Z","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-17T18:41:01.20616Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3ba9ae53cc4335db","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-17T18:41:01.206649Z","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-17T18:41:01.206726Z","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-17T18:41:01.206737Z","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-17T18:41:01.206958Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3ba9ae53cc4335db","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-17T18:41:01.209071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"info","ts":"2026-04-17T18:41:01.210661Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","added-peer-id":"3ba9ae53cc4335db","added-peer-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-04-17T18:41:01.212114Z","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-17T18:41:01.212304Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-04-17T18:41:01.212335Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-04-17T18:41:01.212532Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3ba9ae53cc4335db","initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-17T18:41:01.212588Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-17T18:41:01.583975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db is starting a new election at term 1"} {"level":"info","ts":"2026-04-17T18:41:01.584025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became pre-candidate at term 1"} {"level":"info","ts":"2026-04-17T18:41:01.584045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgPreVoteResp from 3ba9ae53cc4335db at term 1"} {"level":"info","ts":"2026-04-17T18:41:01.584091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became candidate at term 2"} {"level":"info","ts":"2026-04-17T18:41:01.584102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgVoteResp from 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-04-17T18:41:01.58411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became leader at term 2"} {"level":"info","ts":"2026-04-17T18:41:01.584118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3ba9ae53cc4335db elected leader 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-04-17T18:41:01.585127Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T18:41:01.585903Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3ba9ae53cc4335db","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.229:2379]}","request-path":"/0/members/3ba9ae53cc4335db/attributes","cluster-id":"4ad2674607ff1d60","publish-timeout":"7s"} {"level":"info","ts":"2026-04-17T18:41:01.586018Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T18:41:01.586051Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T18:41:01.58653Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-17T18:41:01.586559Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-17T18:41:01.587834Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.229:2379"} {"level":"info","ts":"2026-04-17T18:41:01.588185Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-17T18:41:01.588392Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T18:41:01.588543Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T18:41:01.588622Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T18:41:35.723514Z","caller":"traceutil/trace.go:171","msg":"trace[1209371373] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"225.16881ms","start":"2026-04-17T18:41:35.49832Z","end":"2026-04-17T18:41:35.723489Z","steps":["trace[1209371373] 'read index received' (duration: 222.568988ms)","trace[1209371373] 'applied index is now lower than readState.Index' (duration: 2.598892ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:41:35.723767Z","caller":"traceutil/trace.go:171","msg":"trace[662709836] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"304.421906ms","start":"2026-04-17T18:41:35.419325Z","end":"2026-04-17T18:41:35.723747Z","steps":["trace[662709836] 'process raft request' (duration: 301.629809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:41:35.72437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:41:35.419305Z","time spent":"304.688741ms","remote":"127.0.0.1:50946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:41:35.725013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.689832ms","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-17T18:41:35.725166Z","caller":"traceutil/trace.go:171","msg":"trace[2096204328] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:602; }","duration":"226.889515ms","start":"2026-04-17T18:41:35.498256Z","end":"2026-04-17T18:41:35.725145Z","steps":["trace[2096204328] 'agreement among raft nodes before linearized reading' (duration: 225.449856ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:41:36.869976Z","caller":"traceutil/trace.go:171","msg":"trace[683385053] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"203.689863ms","start":"2026-04-17T18:41:36.666226Z","end":"2026-04-17T18:41:36.869915Z","steps":["trace[683385053] 'process raft request' (duration: 200.177871ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:41:36.870513Z","caller":"traceutil/trace.go:171","msg":"trace[1563107128] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"159.742747ms","start":"2026-04-17T18:41:36.710704Z","end":"2026-04-17T18:41:36.870447Z","steps":["trace[1563107128] 'process raft request' (duration: 159.535543ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:41:37.406836Z","caller":"traceutil/trace.go:171","msg":"trace[114311040] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"111.742528ms","start":"2026-04-17T18:41:37.294976Z","end":"2026-04-17T18:41:37.406719Z","steps":["trace[114311040] 'process raft request' (duration: 104.384719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:41:38.005262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.094287ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:41:38.005386Z","caller":"traceutil/trace.go:171","msg":"trace[1770949059] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"265.917331ms","start":"2026-04-17T18:41:37.739449Z","end":"2026-04-17T18:41:38.005367Z","steps":["trace[1770949059] 'read index received' (duration: 72.376735ms)","trace[1770949059] 'applied index is now lower than readState.Index' (duration: 193.539056ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:41:38.005498Z","caller":"traceutil/trace.go:171","msg":"trace[1463009782] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"536.404705ms","start":"2026-04-17T18:41:37.469066Z","end":"2026-04-17T18:41:38.005471Z","steps":["trace[1463009782] 'process raft request' (duration: 342.813098ms)","trace[1463009782] 'compare' (duration: 192.73473ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:41:38.005603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:41:37.469035Z","time spent":"536.521678ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:41:38.005795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.37582ms","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-17T18:41:38.005858Z","caller":"traceutil/trace.go:171","msg":"trace[215104001] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:616; }","duration":"266.482092ms","start":"2026-04-17T18:41:37.73936Z","end":"2026-04-17T18:41:38.005842Z","steps":["trace[215104001] 'agreement among raft nodes before linearized reading' (duration: 266.104445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:41:38.006292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.660553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-m9gmk\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-17T18:41:38.006402Z","caller":"traceutil/trace.go:171","msg":"trace[762063514] range","detail":"{range_begin:/registry/pods/kube-system/cilium-m9gmk; range_end:; response_count:1; response_revision:616; }","duration":"208.824867ms","start":"2026-04-17T18:41:37.797555Z","end":"2026-04-17T18:41:38.006379Z","steps":["trace[762063514] 'agreement among raft nodes before linearized reading' (duration: 208.036231ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:41:54.19003Z","caller":"traceutil/trace.go:171","msg":"trace[1100483620] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"284.207564ms","start":"2026-04-17T18:41:53.905788Z","end":"2026-04-17T18:41:54.189995Z","steps":["trace[1100483620] 'process raft request' (duration: 283.926758ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:42:23.214677Z","caller":"traceutil/trace.go:171","msg":"trace[1157029073] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"115.512293ms","start":"2026-04-17T18:42:23.09914Z","end":"2026-04-17T18:42:23.214653Z","steps":["trace[1157029073] 'process raft request' (duration: 115.37404ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:42:28.902389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.812218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T18:42:28.902505Z","caller":"traceutil/trace.go:171","msg":"trace[1320783514] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:998; }","duration":"319.997372ms","start":"2026-04-17T18:42:28.582483Z","end":"2026-04-17T18:42:28.90248Z","steps":["trace[1320783514] 'count revisions from in-memory index tree' (duration: 319.744736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:42:28.902561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:42:28.582468Z","time spent":"320.080553ms","remote":"127.0.0.1:51026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-17T18:42:28.902828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.093406ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:42:28.902925Z","caller":"traceutil/trace.go:171","msg":"trace[140572793] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"197.101848ms","start":"2026-04-17T18:42:28.705808Z","end":"2026-04-17T18:42:28.90291Z","steps":["trace[140572793] 'process raft request' (duration: 77.729437ms)","trace[140572793] 'compare' (duration: 118.886442ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:42:29.094099Z","caller":"traceutil/trace.go:171","msg":"trace[1550064786] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"162.966115ms","start":"2026-04-17T18:42:28.931105Z","end":"2026-04-17T18:42:29.094071Z","steps":["trace[1550064786] 'process raft request' (duration: 162.366783ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:42:54.20733Z","caller":"traceutil/trace.go:171","msg":"trace[290708220] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"216.217366ms","start":"2026-04-17T18:42:53.991088Z","end":"2026-04-17T18:42:54.207306Z","steps":["trace[290708220] 'process raft request' (duration: 216.067583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:42:55.258405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.49359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T18:42:55.258524Z","caller":"traceutil/trace.go:171","msg":"trace[717007671] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1084; }","duration":"105.734726ms","start":"2026-04-17T18:42:55.152757Z","end":"2026-04-17T18:42:55.258491Z","steps":["trace[717007671] 'count revisions from in-memory index tree' (duration: 105.400148ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:42:55.510988Z","caller":"traceutil/trace.go:171","msg":"trace[123492843] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"109.331048ms","start":"2026-04-17T18:42:55.40162Z","end":"2026-04-17T18:42:55.510951Z","steps":["trace[123492843] 'process raft request' (duration: 109.180635ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:42:55.519825Z","caller":"traceutil/trace.go:171","msg":"trace[1415127042] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"116.239097ms","start":"2026-04-17T18:42:55.403562Z","end":"2026-04-17T18:42:55.519801Z","steps":["trace[1415127042] 'process raft request' (duration: 116.118295ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:43:08.872492Z","caller":"traceutil/trace.go:171","msg":"trace[1249835332] linearizableReadLoop","detail":"{readStateIndex:1225; appliedIndex:1224; }","duration":"279.948659ms","start":"2026-04-17T18:43:08.592519Z","end":"2026-04-17T18:43:08.872468Z","steps":["trace[1249835332] 'read index received' (duration: 279.792526ms)","trace[1249835332] 'applied index is now lower than readState.Index' (duration: 155.493µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:43:08.872709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.176504ms","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-17T18:43:08.872748Z","caller":"traceutil/trace.go:171","msg":"trace[2003735137] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1180; }","duration":"280.265965ms","start":"2026-04-17T18:43:08.592472Z","end":"2026-04-17T18:43:08.872738Z","steps":["trace[2003735137] 'agreement among raft nodes before linearized reading' (duration: 280.098672ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:43:08.872855Z","caller":"traceutil/trace.go:171","msg":"trace[1498118857] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"282.894539ms","start":"2026-04-17T18:43:08.589954Z","end":"2026-04-17T18:43:08.872848Z","steps":["trace[1498118857] 'process raft request' (duration: 282.41397ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:43:24.521441Z","caller":"traceutil/trace.go:171","msg":"trace[145518917] linearizableReadLoop","detail":"{readStateIndex:1296; appliedIndex:1295; }","duration":"192.465823ms","start":"2026-04-17T18:43:24.328953Z","end":"2026-04-17T18:43:24.521418Z","steps":["trace[145518917] 'read index received' (duration: 192.221648ms)","trace[145518917] 'applied index is now lower than readState.Index' (duration: 243.095µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:43:24.521802Z","caller":"traceutil/trace.go:171","msg":"trace[767166859] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"304.311409ms","start":"2026-04-17T18:43:24.217476Z","end":"2026-04-17T18:43:24.521788Z","steps":["trace[767166859] 'process raft request' (duration: 303.759608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:43:24.521901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:43:24.217461Z","time spent":"304.37645ms","remote":"127.0.0.1:50804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:43:24.522145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.188837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-17T18:43:24.522184Z","caller":"traceutil/trace.go:171","msg":"trace[1620440086] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1247; }","duration":"193.259569ms","start":"2026-04-17T18:43:24.328915Z","end":"2026-04-17T18:43:24.522174Z","steps":["trace[1620440086] 'agreement among raft nodes before linearized reading' (duration: 193.144726ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:43:25.105555Z","caller":"traceutil/trace.go:171","msg":"trace[35739777] linearizableReadLoop","detail":"{readStateIndex:1302; appliedIndex:1301; }","duration":"155.401814ms","start":"2026-04-17T18:43:24.950136Z","end":"2026-04-17T18:43:25.105537Z","steps":["trace[35739777] 'read index received' (duration: 155.210231ms)","trace[35739777] 'applied index is now lower than readState.Index' (duration: 190.813µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:43:25.105634Z","caller":"traceutil/trace.go:171","msg":"trace[87294292] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"162.494168ms","start":"2026-04-17T18:43:24.943105Z","end":"2026-04-17T18:43:25.105599Z","steps":["trace[87294292] 'process raft request' (duration: 162.310114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:43:25.105735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.584628ms","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-17T18:43:25.105764Z","caller":"traceutil/trace.go:171","msg":"trace[1731451205] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1253; }","duration":"155.64579ms","start":"2026-04-17T18:43:24.950111Z","end":"2026-04-17T18:43:25.105757Z","steps":["trace[1731451205] 'agreement among raft nodes before linearized reading' (duration: 155.535477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:43:25.105836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.013742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.229\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-17T18:43:25.105908Z","caller":"traceutil/trace.go:171","msg":"trace[1721608646] range","detail":"{range_begin:/registry/masterleases/199.204.45.229; range_end:; response_count:1; response_revision:1253; }","duration":"126.112004ms","start":"2026-04-17T18:43:24.979771Z","end":"2026-04-17T18:43:25.105883Z","steps":["trace[1721608646] 'agreement among raft nodes before linearized reading' (duration: 125.95289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:43:25.540436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.898173ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:43:25.540601Z","caller":"traceutil/trace.go:171","msg":"trace[1025772454] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"267.760411ms","start":"2026-04-17T18:43:25.272816Z","end":"2026-04-17T18:43:25.540577Z","steps":["trace[1025772454] 'process raft request' (duration: 64.666744ms)","trace[1025772454] 'compare' (duration: 202.652659ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:43:25.540527Z","caller":"traceutil/trace.go:171","msg":"trace[152369021] linearizableReadLoop","detail":"{readStateIndex:1306; appliedIndex:1305; }","duration":"118.552352ms","start":"2026-04-17T18:43:25.421961Z","end":"2026-04-17T18:43:25.540513Z","steps":["trace[152369021] 'read index received' (duration: 47.701µs)","trace[152369021] 'applied index is now lower than readState.Index' (duration: 118.503451ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:43:25.540752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.795267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:43:25.540774Z","caller":"traceutil/trace.go:171","msg":"trace[392370078] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1256; }","duration":"118.857398ms","start":"2026-04-17T18:43:25.421909Z","end":"2026-04-17T18:43:25.540766Z","steps":["trace[392370078] 'agreement among raft nodes before linearized reading' (duration: 118.774367ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:43:25.686912Z","caller":"traceutil/trace.go:171","msg":"trace[337471052] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"107.545839ms","start":"2026-04-17T18:43:25.579333Z","end":"2026-04-17T18:43:25.686879Z","steps":["trace[337471052] 'process raft request' (duration: 107.414737ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:43:25.68702Z","caller":"traceutil/trace.go:171","msg":"trace[830471595] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"109.752464ms","start":"2026-04-17T18:43:25.577232Z","end":"2026-04-17T18:43:25.686985Z","steps":["trace[830471595] 'process raft request' (duration: 72.962162ms)","trace[830471595] 'compare' (duration: 36.411744ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:43:25.68702Z","caller":"traceutil/trace.go:171","msg":"trace[241443985] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"102.429927ms","start":"2026-04-17T18:43:25.584516Z","end":"2026-04-17T18:43:25.686946Z","steps":["trace[241443985] 'process raft request' (duration: 102.273284ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:43:41.401122Z","caller":"traceutil/trace.go:171","msg":"trace[1014441995] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"221.483197ms","start":"2026-04-17T18:43:41.179612Z","end":"2026-04-17T18:43:41.401095Z","steps":["trace[1014441995] 'process raft request' (duration: 221.243833ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:43:41.40109Z","caller":"traceutil/trace.go:171","msg":"trace[1040487824] linearizableReadLoop","detail":"{readStateIndex:1414; appliedIndex:1413; }","duration":"162.59882ms","start":"2026-04-17T18:43:41.238462Z","end":"2026-04-17T18:43:41.401061Z","steps":["trace[1040487824] 'read index received' (duration: 162.422026ms)","trace[1040487824] 'applied index is now lower than readState.Index' (duration: 175.464µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:43:41.401321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.841904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-17T18:43:41.401379Z","caller":"traceutil/trace.go:171","msg":"trace[1989245669] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1361; }","duration":"162.964358ms","start":"2026-04-17T18:43:41.238398Z","end":"2026-04-17T18:43:41.401363Z","steps":["trace[1989245669] 'agreement among raft nodes before linearized reading' (duration: 162.760783ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:43:55.897974Z","caller":"traceutil/trace.go:171","msg":"trace[2037648962] linearizableReadLoop","detail":"{readStateIndex:1484; appliedIndex:1483; }","duration":"129.126425ms","start":"2026-04-17T18:43:55.76883Z","end":"2026-04-17T18:43:55.897957Z","steps":["trace[2037648962] 'read index received' (duration: 128.927411ms)","trace[2037648962] 'applied index is now lower than readState.Index' (duration: 198.294µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:43:55.898074Z","caller":"traceutil/trace.go:171","msg":"trace[1438875867] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"150.24764ms","start":"2026-04-17T18:43:55.747818Z","end":"2026-04-17T18:43:55.898066Z","steps":["trace[1438875867] 'process raft request' (duration: 150.019665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:43:55.898325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.477122ms","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-04-17T18:43:55.898394Z","caller":"traceutil/trace.go:171","msg":"trace[734965188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1428; }","duration":"129.582844ms","start":"2026-04-17T18:43:55.768795Z","end":"2026-04-17T18:43:55.898378Z","steps":["trace[734965188] 'agreement among raft nodes before linearized reading' (duration: 129.412361ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:46:56.438581Z","caller":"traceutil/trace.go:171","msg":"trace[1026525758] transaction","detail":"{read_only:false; response_revision:2285; number_of_response:1; }","duration":"102.547538ms","start":"2026-04-17T18:46:56.336009Z","end":"2026-04-17T18:46:56.438557Z","steps":["trace[1026525758] 'process raft request' (duration: 11.675795ms)","trace[1026525758] 'compare' (duration: 90.542217ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:46:56.438973Z","caller":"traceutil/trace.go:171","msg":"trace[2052926860] transaction","detail":"{read_only:false; response_revision:2286; number_of_response:1; }","duration":"100.20874ms","start":"2026-04-17T18:46:56.338753Z","end":"2026-04-17T18:46:56.438962Z","steps":["trace[2052926860] 'process raft request' (duration: 99.71335ms)"],"step_count":1}