{"level":"warn","ts":"2026-04-06T21:04:38.696486Z","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-06T21:04:38.696586Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.227: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.227:2380","--initial-cluster=instance=https://199.204.45.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.227: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-06T21:04:38.696691Z","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-06T21:04:38.696717Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-04-06T21:04:38.696744Z","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-06T21:04:38.697369Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"]} {"level":"info","ts":"2026-04-06T21:04:38.697818Z","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.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.227: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-06T21:04:38.70428Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.218276ms"} {"level":"info","ts":"2026-04-06T21:04:38.715378Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"572db18442eba1c2","cluster-id":"fb1028a037bf3cd6"} {"level":"info","ts":"2026-04-06T21:04:38.71553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=()"} {"level":"info","ts":"2026-04-06T21:04:38.715556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 0"} {"level":"info","ts":"2026-04-06T21:04:38.715573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 572db18442eba1c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-06T21:04:38.715582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 1"} {"level":"info","ts":"2026-04-06T21:04:38.715629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"warn","ts":"2026-04-06T21:04:38.723204Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-06T21:04:38.724582Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-06T21:04:38.725819Z","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-06T21:04:38.728056Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"572db18442eba1c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-06T21:04:38.728722Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"572db18442eba1c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-06T21:04:38.728839Z","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-06T21:04:38.728876Z","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-06T21:04:38.728888Z","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-06T21:04:38.729153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"info","ts":"2026-04-06T21:04:38.729705Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","added-peer-id":"572db18442eba1c2","added-peer-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-04-06T21:04:38.735239Z","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-06T21:04:38.735362Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-04-06T21:04:38.735401Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-04-06T21:04:38.735639Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"572db18442eba1c2","initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-06T21:04:38.735709Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-06T21:04:39.716691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 is starting a new election at term 1"} {"level":"info","ts":"2026-04-06T21:04:39.71676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-06T21:04:39.716797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgPreVoteResp from 572db18442eba1c2 at term 1"} {"level":"info","ts":"2026-04-06T21:04:39.716879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became candidate at term 2"} {"level":"info","ts":"2026-04-06T21:04:39.716892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgVoteResp from 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-04-06T21:04:39.716943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became leader at term 2"} {"level":"info","ts":"2026-04-06T21:04:39.716958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 572db18442eba1c2 elected leader 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-04-06T21:04:39.718002Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T21:04:39.718924Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"572db18442eba1c2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.227:2379]}","request-path":"/0/members/572db18442eba1c2/attributes","cluster-id":"fb1028a037bf3cd6","publish-timeout":"7s"} {"level":"info","ts":"2026-04-06T21:04:39.719115Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-06T21:04:39.719155Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-06T21:04:39.719431Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T21:04:39.719625Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T21:04:39.719703Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T21:04:39.723279Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-06T21:04:39.728312Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-06T21:04:39.728333Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-06T21:04:39.731649Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.227:2379"} {"level":"info","ts":"2026-04-06T21:05:09.714269Z","caller":"traceutil/trace.go:171","msg":"trace[123573143] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"328.03668ms","start":"2026-04-06T21:05:09.386196Z","end":"2026-04-06T21:05:09.714233Z","steps":["trace[123573143] 'process raft request' (duration: 327.822567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:05:09.71436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.862469ms","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-06T21:05:09.714246Z","caller":"traceutil/trace.go:171","msg":"trace[1585014823] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:552; }","duration":"324.737096ms","start":"2026-04-06T21:05:09.38947Z","end":"2026-04-06T21:05:09.714208Z","steps":["trace[1585014823] 'read index received' (duration: 324.706665ms)","trace[1585014823] 'applied index is now lower than readState.Index' (duration: 29.241µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:05:09.714459Z","caller":"traceutil/trace.go:171","msg":"trace[1170432055] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:531; }","duration":"325.007181ms","start":"2026-04-06T21:05:09.389442Z","end":"2026-04-06T21:05:09.714449Z","steps":["trace[1170432055] 'agreement among raft nodes before linearized reading' (duration: 324.852429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:05:09.714501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:05:09.38943Z","time spent":"325.061892ms","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-06T21:05:09.714918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:05:09.386174Z","time spent":"328.183313ms","remote":"127.0.0.1:40852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T21:05:09.725907Z","caller":"traceutil/trace.go:171","msg":"trace[1682945081] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"173.34725ms","start":"2026-04-06T21:05:09.55254Z","end":"2026-04-06T21:05:09.725888Z","steps":["trace[1682945081] 'process raft request' (duration: 168.834553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:05:09.726077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.695014ms","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-06T21:05:09.726104Z","caller":"traceutil/trace.go:171","msg":"trace[1203542997] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:533; }","duration":"137.753946ms","start":"2026-04-06T21:05:09.58834Z","end":"2026-04-06T21:05:09.726094Z","steps":["trace[1203542997] 'agreement among raft nodes before linearized reading' (duration: 137.682984ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:05:15.637838Z","caller":"traceutil/trace.go:171","msg":"trace[1988778823] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"422.691486ms","start":"2026-04-06T21:05:15.215117Z","end":"2026-04-06T21:05:15.637808Z","steps":["trace[1988778823] 'read index received' (duration: 421.761989ms)","trace[1988778823] 'applied index is now lower than readState.Index' (duration: 928.267µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:05:15.638004Z","caller":"traceutil/trace.go:171","msg":"trace[1094297897] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"444.527838ms","start":"2026-04-06T21:05:15.193462Z","end":"2026-04-06T21:05:15.63799Z","steps":["trace[1094297897] 'process raft request' (duration: 443.696752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:05:15.638145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:05:15.193429Z","time spent":"444.62347ms","remote":"127.0.0.1:40892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T21:05:15.638261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.02635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-06T21:05:15.638338Z","caller":"traceutil/trace.go:171","msg":"trace[223056661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:575; }","duration":"378.121682ms","start":"2026-04-06T21:05:15.260196Z","end":"2026-04-06T21:05:15.638318Z","steps":["trace[223056661] 'agreement among raft nodes before linearized reading' (duration: 377.890928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:05:15.638385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:05:15.260148Z","time spent":"378.225574ms","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-06T21:05:15.638656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.511553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-57x6l\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-04-06T21:05:15.638727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.394298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-06T21:05:15.638786Z","caller":"traceutil/trace.go:171","msg":"trace[1424640687] range","detail":"{range_begin:/registry/pods/kube-system/cilium-57x6l; range_end:; response_count:1; response_revision:575; }","duration":"423.707546ms","start":"2026-04-06T21:05:15.21506Z","end":"2026-04-06T21:05:15.638768Z","steps":["trace[1424640687] 'agreement among raft nodes before linearized reading' (duration: 423.405651ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:05:15.638819Z","caller":"traceutil/trace.go:171","msg":"trace[1915308407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:575; }","duration":"377.53973ms","start":"2026-04-06T21:05:15.261252Z","end":"2026-04-06T21:05:15.638792Z","steps":["trace[1915308407] 'agreement among raft nodes before linearized reading' (duration: 377.262555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:05:15.638828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:05:15.215041Z","time spent":"423.777308ms","remote":"127.0.0.1:41000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-57x6l\" "} {"level":"warn","ts":"2026-04-06T21:05:15.638866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:05:15.261231Z","time spent":"377.626552ms","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-06T21:05:15.848606Z","caller":"traceutil/trace.go:171","msg":"trace[395880513] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"201.957849ms","start":"2026-04-06T21:05:15.646613Z","end":"2026-04-06T21:05:15.848571Z","steps":["trace[395880513] 'process raft request' (duration: 140.732462ms)","trace[395880513] 'compare' (duration: 60.920361ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:05:15.848683Z","caller":"traceutil/trace.go:171","msg":"trace[858805269] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"195.576876ms","start":"2026-04-06T21:05:15.653039Z","end":"2026-04-06T21:05:15.848616Z","steps":["trace[858805269] 'process raft request' (duration: 195.439884ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:05:15.848605Z","caller":"traceutil/trace.go:171","msg":"trace[606476039] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:598; }","duration":"118.389074ms","start":"2026-04-06T21:05:15.730189Z","end":"2026-04-06T21:05:15.848579Z","steps":["trace[606476039] 'read index received' (duration: 57.135517ms)","trace[606476039] 'applied index is now lower than readState.Index' (duration: 61.252427ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:05:15.848859Z","caller":"traceutil/trace.go:171","msg":"trace[861221523] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"200.921078ms","start":"2026-04-06T21:05:15.647895Z","end":"2026-04-06T21:05:15.848816Z","steps":["trace[861221523] 'process raft request' (duration: 200.543811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:05:15.848989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.783521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:05:15.849037Z","caller":"traceutil/trace.go:171","msg":"trace[1800522377] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"118.842672ms","start":"2026-04-06T21:05:15.730183Z","end":"2026-04-06T21:05:15.849026Z","steps":["trace[1800522377] 'agreement among raft nodes before linearized reading' (duration: 118.758261ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:05:15.849209Z","caller":"traceutil/trace.go:171","msg":"trace[558107168] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"159.590134ms","start":"2026-04-06T21:05:15.689609Z","end":"2026-04-06T21:05:15.849199Z","steps":["trace[558107168] 'process raft request' (duration: 158.921562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:05:15.849291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.365977ms","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-06T21:05:15.849355Z","caller":"traceutil/trace.go:171","msg":"trace[1882425406] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:579; }","duration":"100.526301ms","start":"2026-04-06T21:05:15.748816Z","end":"2026-04-06T21:05:15.849343Z","steps":["trace[1882425406] 'agreement among raft nodes before linearized reading' (duration: 100.296926ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:05:30.509948Z","caller":"traceutil/trace.go:171","msg":"trace[271922970] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"197.230217ms","start":"2026-04-06T21:05:30.312695Z","end":"2026-04-06T21:05:30.509925Z","steps":["trace[271922970] 'process raft request' (duration: 197.075284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:06:28.043467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.476813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:06:28.043571Z","caller":"traceutil/trace.go:171","msg":"trace[468812737] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1028; }","duration":"231.657778ms","start":"2026-04-06T21:06:27.811892Z","end":"2026-04-06T21:06:28.04355Z","steps":["trace[468812737] 'count revisions from in-memory index tree' (duration: 231.369251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:06:28.231035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.207099ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-06T21:06:29.570556Z","caller":"traceutil/trace.go:171","msg":"trace[124084985] linearizableReadLoop","detail":"{readStateIndex:1075; appliedIndex:1074; }","duration":"147.373774ms","start":"2026-04-06T21:06:29.423155Z","end":"2026-04-06T21:06:29.570529Z","steps":["trace[124084985] 'read index received' (duration: 147.21322ms)","trace[124084985] 'applied index is now lower than readState.Index' (duration: 159.424µs)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:06:29.57071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.532876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:06:29.57068Z","caller":"traceutil/trace.go:171","msg":"trace[1507720877] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"162.808929ms","start":"2026-04-06T21:06:29.40784Z","end":"2026-04-06T21:06:29.570649Z","steps":["trace[1507720877] 'process raft request' (duration: 162.515352ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:06:29.570747Z","caller":"traceutil/trace.go:171","msg":"trace[419299474] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1033; }","duration":"147.594987ms","start":"2026-04-06T21:06:29.423141Z","end":"2026-04-06T21:06:29.570736Z","steps":["trace[419299474] 'agreement among raft nodes before linearized reading' (duration: 147.487605ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:06:30.510302Z","caller":"traceutil/trace.go:171","msg":"trace[127648906] linearizableReadLoop","detail":"{readStateIndex:1078; appliedIndex:1077; }","duration":"145.763332ms","start":"2026-04-06T21:06:30.364516Z","end":"2026-04-06T21:06:30.51028Z","steps":["trace[127648906] 'read index received' (duration: 129.281596ms)","trace[127648906] 'applied index is now lower than readState.Index' (duration: 16.480956ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:06:30.510402Z","caller":"traceutil/trace.go:171","msg":"trace[1547137967] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"161.996163ms","start":"2026-04-06T21:06:30.348367Z","end":"2026-04-06T21:06:30.510363Z","steps":["trace[1547137967] 'process raft request' (duration: 145.356344ms)","trace[1547137967] 'compare' (duration: 16.473396ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:06:30.510521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.746412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-06T21:06:30.510567Z","caller":"traceutil/trace.go:171","msg":"trace[1079710246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1036; }","duration":"145.826793ms","start":"2026-04-06T21:06:30.36473Z","end":"2026-04-06T21:06:30.510557Z","steps":["trace[1079710246] 'agreement among raft nodes before linearized reading' (duration: 145.67767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:06:30.510599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.044737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-06T21:06:30.510691Z","caller":"traceutil/trace.go:171","msg":"trace[1774084514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1036; }","duration":"146.19641ms","start":"2026-04-06T21:06:30.364472Z","end":"2026-04-06T21:06:30.510668Z","steps":["trace[1774084514] 'agreement among raft nodes before linearized reading' (duration: 145.919405ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:07:00.85408Z","caller":"traceutil/trace.go:171","msg":"trace[320226862] linearizableReadLoop","detail":"{readStateIndex:1263; appliedIndex:1262; }","duration":"141.49564ms","start":"2026-04-06T21:07:00.712566Z","end":"2026-04-06T21:07:00.854062Z","steps":["trace[320226862] 'read index received' (duration: 141.325807ms)","trace[320226862] 'applied index is now lower than readState.Index' (duration: 169.283µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:07:00.854118Z","caller":"traceutil/trace.go:171","msg":"trace[1946618344] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"144.435297ms","start":"2026-04-06T21:07:00.709665Z","end":"2026-04-06T21:07:00.8541Z","steps":["trace[1946618344] 'process raft request' (duration: 144.285394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:07:00.854252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.678364ms","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-04-06T21:07:00.854283Z","caller":"traceutil/trace.go:171","msg":"trace[225266561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1213; }","duration":"141.749575ms","start":"2026-04-06T21:07:00.712528Z","end":"2026-04-06T21:07:00.854277Z","steps":["trace[225266561] 'agreement among raft nodes before linearized reading' (duration: 141.619172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:07:00.85433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.925452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:07:00.854389Z","caller":"traceutil/trace.go:171","msg":"trace[1470841900] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1213; }","duration":"125.017255ms","start":"2026-04-06T21:07:00.729355Z","end":"2026-04-06T21:07:00.854373Z","steps":["trace[1470841900] 'agreement among raft nodes before linearized reading' (duration: 124.891772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:07:00.854392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.40746ms","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-06T21:07:00.854467Z","caller":"traceutil/trace.go:171","msg":"trace[503885869] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1213; }","duration":"139.497932ms","start":"2026-04-06T21:07:00.71495Z","end":"2026-04-06T21:07:00.854448Z","steps":["trace[503885869] 'agreement among raft nodes before linearized reading' (duration: 139.275228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:07:01.107716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.706043ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T21:07:01.108045Z","caller":"traceutil/trace.go:171","msg":"trace[1114890550] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"247.743956ms","start":"2026-04-06T21:07:00.860289Z","end":"2026-04-06T21:07:01.108033Z","steps":["trace[1114890550] 'process raft request' (duration: 247.500241ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:07:01.108144Z","caller":"traceutil/trace.go:171","msg":"trace[1162765308] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"249.598621ms","start":"2026-04-06T21:07:00.858426Z","end":"2026-04-06T21:07:01.108025Z","steps":["trace[1162765308] 'process raft request' (duration: 142.50818ms)","trace[1162765308] 'compare' (duration: 106.600092ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:07:01.881861Z","caller":"traceutil/trace.go:171","msg":"trace[1877757729] linearizableReadLoop","detail":"{readStateIndex:1267; appliedIndex:1266; }","duration":"116.830648ms","start":"2026-04-06T21:07:01.765014Z","end":"2026-04-06T21:07:01.881845Z","steps":["trace[1877757729] 'read index received' (duration: 116.747696ms)","trace[1877757729] 'applied index is now lower than readState.Index' (duration: 82.402µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:07:01.881901Z","caller":"traceutil/trace.go:171","msg":"trace[264584342] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"120.894335ms","start":"2026-04-06T21:07:01.760994Z","end":"2026-04-06T21:07:01.881888Z","steps":["trace[264584342] 'process raft request' (duration: 120.748682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:07:01.882091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.057032ms","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-06T21:07:01.882185Z","caller":"traceutil/trace.go:171","msg":"trace[1561187759] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1217; }","duration":"117.197705ms","start":"2026-04-06T21:07:01.764965Z","end":"2026-04-06T21:07:01.882162Z","steps":["trace[1561187759] 'agreement among raft nodes before linearized reading' (duration: 116.95302ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:07:14.298686Z","caller":"traceutil/trace.go:171","msg":"trace[1670465211] linearizableReadLoop","detail":"{readStateIndex:1360; appliedIndex:1358; }","duration":"306.789817ms","start":"2026-04-06T21:07:13.991872Z","end":"2026-04-06T21:07:14.298662Z","steps":["trace[1670465211] 'read index received' (duration: 22.160297ms)","trace[1670465211] 'applied index is now lower than readState.Index' (duration: 284.6285ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:07:14.298751Z","caller":"traceutil/trace.go:171","msg":"trace[430265214] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"344.664119ms","start":"2026-04-06T21:07:13.954051Z","end":"2026-04-06T21:07:14.298715Z","steps":["trace[430265214] 'process raft request' (duration: 344.433094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:07:14.298936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:07:13.954025Z","time spent":"344.818811ms","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T21:07:14.298979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.084452ms","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-06T21:07:14.299046Z","caller":"traceutil/trace.go:171","msg":"trace[1755495565] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1307; }","duration":"307.216894ms","start":"2026-04-06T21:07:13.991816Z","end":"2026-04-06T21:07:14.299033Z","steps":["trace[1755495565] 'agreement among raft nodes before linearized reading' (duration: 306.98026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:07:14.299087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:07:13.991798Z","time spent":"307.281065ms","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-06T21:07:14.299354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.530659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-04-06T21:07:14.299402Z","caller":"traceutil/trace.go:171","msg":"trace[1075348515] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1307; }","duration":"126.634291ms","start":"2026-04-06T21:07:14.172752Z","end":"2026-04-06T21:07:14.299386Z","steps":["trace[1075348515] 'agreement among raft nodes before linearized reading' (duration: 126.11484ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:10:30.057249Z","caller":"traceutil/trace.go:171","msg":"trace[1364652348] transaction","detail":"{read_only:false; response_revision:2332; number_of_response:1; }","duration":"102.887298ms","start":"2026-04-06T21:10:29.954285Z","end":"2026-04-06T21:10:30.057172Z","steps":["trace[1364652348] 'process raft request' (duration: 102.573692ms)"],"step_count":1}