{"level":"warn","ts":"2026-04-21T21:10:43.472972Z","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-21T21:10:43.473101Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.246: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.246:2380","--initial-cluster=instance=https://199.204.45.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.246: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-21T21:10:43.473226Z","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-21T21:10:43.473248Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-04-21T21:10:43.473279Z","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-21T21:10:43.474264Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"]} {"level":"info","ts":"2026-04-21T21:10:43.474388Z","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.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.246: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-21T21:10:43.479151Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.47913ms"} {"level":"info","ts":"2026-04-21T21:10:43.487731Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab97322ebdd79664","cluster-id":"87bc89380c3dc2c"} {"level":"info","ts":"2026-04-21T21:10:43.487823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=()"} {"level":"info","ts":"2026-04-21T21:10:43.487858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 0"} {"level":"info","ts":"2026-04-21T21:10:43.487879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab97322ebdd79664 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-21T21:10:43.487902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 1"} {"level":"info","ts":"2026-04-21T21:10:43.487957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"warn","ts":"2026-04-21T21:10:43.494273Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-21T21:10:43.495805Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-21T21:10:43.496991Z","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-21T21:10:43.499257Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab97322ebdd79664","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-21T21:10:43.499442Z","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-21T21:10:43.499574Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab97322ebdd79664","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-21T21:10:43.499695Z","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-21T21:10:43.501715Z","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-21T21:10:43.502058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"info","ts":"2026-04-21T21:10:43.502267Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","added-peer-id":"ab97322ebdd79664","added-peer-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-04-21T21:10:43.504971Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-04-21T21:10:43.505Z","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-21T21:10:43.50502Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-04-21T21:10:43.505671Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab97322ebdd79664","initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-21T21:10:43.505747Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-21T21:10:43.689261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 is starting a new election at term 1"} {"level":"info","ts":"2026-04-21T21:10:43.689353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-21T21:10:43.689391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgPreVoteResp from ab97322ebdd79664 at term 1"} {"level":"info","ts":"2026-04-21T21:10:43.689416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became candidate at term 2"} {"level":"info","ts":"2026-04-21T21:10:43.689428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgVoteResp from ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-04-21T21:10:43.68963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became leader at term 2"} {"level":"info","ts":"2026-04-21T21:10:43.689719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab97322ebdd79664 elected leader ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-04-21T21:10:43.697432Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab97322ebdd79664","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.246:2379]}","request-path":"/0/members/ab97322ebdd79664/attributes","cluster-id":"87bc89380c3dc2c","publish-timeout":"7s"} {"level":"info","ts":"2026-04-21T21:10:43.697476Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T21:10:43.697701Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T21:10:43.699899Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T21:10:43.700259Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-21T21:10:43.700254Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-21T21:10:43.70033Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-21T21:10:43.703515Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T21:10:43.703586Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T21:10:43.703607Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T21:10:43.711648Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.246:2379"} {"level":"info","ts":"2026-04-21T21:11:03.43945Z","caller":"traceutil/trace.go:171","msg":"trace[2031617655] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"556.125345ms","start":"2026-04-21T21:11:02.883294Z","end":"2026-04-21T21:11:03.439419Z","steps":["trace[2031617655] 'process raft request' (duration: 532.241377ms)","trace[2031617655] 'compare' (duration: 23.734844ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:11:03.440584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:11:02.883272Z","time spent":"556.29434ms","remote":"127.0.0.1:53734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5149,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T21:11:03.650275Z","caller":"traceutil/trace.go:171","msg":"trace[71710991] linearizableReadLoop","detail":"{readStateIndex:466; appliedIndex:465; }","duration":"223.594206ms","start":"2026-04-21T21:11:03.426632Z","end":"2026-04-21T21:11:03.650226Z","steps":["trace[71710991] 'read index received' (duration: 178.781788ms)","trace[71710991] 'applied index is now lower than readState.Index' (duration: 44.810448ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:11:03.650553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.891024ms","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-21T21:11:03.650644Z","caller":"traceutil/trace.go:171","msg":"trace[949199516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:451; }","duration":"224.033828ms","start":"2026-04-21T21:11:03.426593Z","end":"2026-04-21T21:11:03.650627Z","steps":["trace[949199516] 'agreement among raft nodes before linearized reading' (duration: 223.861923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:11:03.651177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.718708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T21:11:03.651378Z","caller":"traceutil/trace.go:171","msg":"trace[601801797] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:451; }","duration":"159.857212ms","start":"2026-04-21T21:11:03.491408Z","end":"2026-04-21T21:11:03.651265Z","steps":["trace[601801797] 'agreement among raft nodes before linearized reading' (duration: 159.650006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:11:19.955263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.243232ms","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-21T21:11:19.955362Z","caller":"traceutil/trace.go:171","msg":"trace[1639855728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:614; }","duration":"203.392076ms","start":"2026-04-21T21:11:19.75195Z","end":"2026-04-21T21:11:19.955342Z","steps":["trace[1639855728] 'range keys from in-memory index tree' (duration: 203.070807ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:11:19.955831Z","caller":"traceutil/trace.go:171","msg":"trace[1501280548] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"201.397683ms","start":"2026-04-21T21:11:19.754201Z","end":"2026-04-21T21:11:19.955599Z","steps":["trace[1501280548] 'process raft request' (duration: 171.153298ms)","trace[1501280548] 'compare' (duration: 29.662419ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T21:11:20.337834Z","caller":"traceutil/trace.go:171","msg":"trace[1907862128] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"193.007793ms","start":"2026-04-21T21:11:20.14479Z","end":"2026-04-21T21:11:20.337798Z","steps":["trace[1907862128] 'read index received' (duration: 191.481273ms)","trace[1907862128] 'applied index is now lower than readState.Index' (duration: 1.52504ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T21:11:20.337903Z","caller":"traceutil/trace.go:171","msg":"trace[101463690] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"213.833841ms","start":"2026-04-21T21:11:20.124033Z","end":"2026-04-21T21:11:20.337867Z","steps":["trace[101463690] 'process raft request' (duration: 212.439864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:11:20.338095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.28445ms","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-21T21:11:20.33819Z","caller":"traceutil/trace.go:171","msg":"trace[2139528047] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:617; }","duration":"193.427175ms","start":"2026-04-21T21:11:20.144739Z","end":"2026-04-21T21:11:20.338167Z","steps":["trace[2139528047] 'agreement among raft nodes before linearized reading' (duration: 193.187659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:11:20.367122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.752743ms","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-21T21:11:20.367201Z","caller":"traceutil/trace.go:171","msg":"trace[1346612509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:617; }","duration":"209.858527ms","start":"2026-04-21T21:11:20.157324Z","end":"2026-04-21T21:11:20.367183Z","steps":["trace[1346612509] 'agreement among raft nodes before linearized reading' (duration: 180.956517ms)","trace[1346612509] 'range keys from in-memory index tree' (duration: 28.752516ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:11:20.367449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.395445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-21T21:11:20.367578Z","caller":"traceutil/trace.go:171","msg":"trace[532813016] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:617; }","duration":"155.59026ms","start":"2026-04-21T21:11:20.211955Z","end":"2026-04-21T21:11:20.367545Z","steps":["trace[532813016] 'agreement among raft nodes before linearized reading' (duration: 126.340441ms)","trace[532813016] 'range keys from in-memory index tree' (duration: 28.850278ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:11:20.993322Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959990679479559,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T21:11:21.055595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.486548ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T21:11:21.055748Z","caller":"traceutil/trace.go:171","msg":"trace[602426376] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:640; }","duration":"563.342607ms","start":"2026-04-21T21:11:20.492394Z","end":"2026-04-21T21:11:21.055737Z","steps":["trace[602426376] 'read index received' (duration: 523.86823ms)","trace[602426376] 'applied index is now lower than readState.Index' (duration: 39.473667ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:11:21.055802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.410499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T21:11:21.055819Z","caller":"traceutil/trace.go:171","msg":"trace[1672468598] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"563.472371ms","start":"2026-04-21T21:11:20.492342Z","end":"2026-04-21T21:11:21.055814Z","steps":["trace[1672468598] 'agreement among raft nodes before linearized reading' (duration: 563.42855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:11:21.055838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:11:20.492323Z","time spent":"563.511522ms","remote":"127.0.0.1:53536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-21T21:11:21.055901Z","caller":"traceutil/trace.go:171","msg":"trace[424632075] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"672.419884ms","start":"2026-04-21T21:11:20.383454Z","end":"2026-04-21T21:11:21.055874Z","steps":["trace[424632075] 'process raft request' (duration: 672.216369ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:11:21.055929Z","caller":"traceutil/trace.go:171","msg":"trace[1016728945] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"683.847785ms","start":"2026-04-21T21:11:20.372049Z","end":"2026-04-21T21:11:21.055897Z","steps":["trace[1016728945] 'process raft request' (duration: 102.886625ms)","trace[1016728945] 'compare' (duration: 580.073686ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T21:11:21.055954Z","caller":"traceutil/trace.go:171","msg":"trace[759224163] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"665.81332ms","start":"2026-04-21T21:11:20.390116Z","end":"2026-04-21T21:11:21.055929Z","steps":["trace[759224163] 'process raft request' (duration: 665.601685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:11:21.056068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:11:20.383439Z","time spent":"672.525286ms","remote":"127.0.0.1:53734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T21:11:21.056077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:11:20.372034Z","time spent":"683.986418ms","remote":"127.0.0.1:53826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T21:11:21.056103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:11:20.390099Z","time spent":"665.948253ms","remote":"127.0.0.1:54036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T21:11:36.570108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.592889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-21T21:11:36.570222Z","caller":"traceutil/trace.go:171","msg":"trace[436361727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:780; }","duration":"120.746254ms","start":"2026-04-21T21:11:36.44945Z","end":"2026-04-21T21:11:36.570197Z","steps":["trace[436361727] 'range keys from in-memory index tree' (duration: 120.499567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:11:36.570439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.964785ms","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-21T21:11:36.570542Z","caller":"traceutil/trace.go:171","msg":"trace[930089641] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:780; }","duration":"122.173581ms","start":"2026-04-21T21:11:36.448342Z","end":"2026-04-21T21:11:36.570515Z","steps":["trace[930089641] 'range keys from in-memory index tree' (duration: 121.7548ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:11.774017Z","caller":"traceutil/trace.go:171","msg":"trace[1066579850] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"252.795557ms","start":"2026-04-21T21:12:11.521193Z","end":"2026-04-21T21:12:11.773989Z","steps":["trace[1066579850] 'process raft request' (duration: 252.646773ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:35.653995Z","caller":"traceutil/trace.go:171","msg":"trace[343519490] linearizableReadLoop","detail":"{readStateIndex:1110; appliedIndex:1109; }","duration":"162.438994ms","start":"2026-04-21T21:12:35.491533Z","end":"2026-04-21T21:12:35.653972Z","steps":["trace[343519490] 'read index received' (duration: 162.322011ms)","trace[343519490] 'applied index is now lower than readState.Index' (duration: 116.113µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T21:12:35.65418Z","caller":"traceutil/trace.go:171","msg":"trace[1321155924] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"209.035068ms","start":"2026-04-21T21:12:35.44502Z","end":"2026-04-21T21:12:35.654055Z","steps":["trace[1321155924] 'process raft request' (duration: 208.808562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:35.65419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.643739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T21:12:35.654439Z","caller":"traceutil/trace.go:171","msg":"trace[185940769] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1073; }","duration":"162.940217ms","start":"2026-04-21T21:12:35.491486Z","end":"2026-04-21T21:12:35.654426Z","steps":["trace[185940769] 'agreement among raft nodes before linearized reading' (duration: 162.585147ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:36.65331Z","caller":"traceutil/trace.go:171","msg":"trace[1575869952] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"231.242016ms","start":"2026-04-21T21:12:36.422036Z","end":"2026-04-21T21:12:36.653278Z","steps":["trace[1575869952] 'process raft request' (duration: 230.069285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:36.65368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.646552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T21:12:36.653807Z","caller":"traceutil/trace.go:171","msg":"trace[1461450350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1075; }","duration":"161.850068ms","start":"2026-04-21T21:12:36.491909Z","end":"2026-04-21T21:12:36.653759Z","steps":["trace[1461450350] 'agreement among raft nodes before linearized reading' (duration: 161.510618ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:36.65392Z","caller":"traceutil/trace.go:171","msg":"trace[571586307] linearizableReadLoop","detail":"{readStateIndex:1112; appliedIndex:1111; }","duration":"161.242302ms","start":"2026-04-21T21:12:36.491998Z","end":"2026-04-21T21:12:36.653241Z","steps":["trace[571586307] 'read index received' (duration: 160.05009ms)","trace[571586307] 'applied index is now lower than readState.Index' (duration: 1.190062ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T21:12:37.050691Z","caller":"traceutil/trace.go:171","msg":"trace[810724706] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"110.664551ms","start":"2026-04-21T21:12:36.939977Z","end":"2026-04-21T21:12:37.050641Z","steps":["trace[810724706] 'process raft request' (duration: 110.472026ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:12:37.979872Z","caller":"traceutil/trace.go:171","msg":"trace[1658567952] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"103.43403ms","start":"2026-04-21T21:12:37.876405Z","end":"2026-04-21T21:12:37.979839Z","steps":["trace[1658567952] 'process raft request' (duration: 43.687367ms)","trace[1658567952] 'compare' (duration: 59.62327ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T21:12:50.18292Z","caller":"traceutil/trace.go:171","msg":"trace[571704408] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"385.848682ms","start":"2026-04-21T21:12:49.797037Z","end":"2026-04-21T21:12:50.182886Z","steps":["trace[571704408] 'process raft request' (duration: 385.672427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:50.183128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:49.797018Z","time spent":"386.004946ms","remote":"127.0.0.1:53826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T21:12:50.367675Z","caller":"traceutil/trace.go:171","msg":"trace[1953627810] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"331.161423ms","start":"2026-04-21T21:12:50.036469Z","end":"2026-04-21T21:12:50.367631Z","steps":["trace[1953627810] 'process raft request' (duration: 320.698976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:12:50.367872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:12:50.036436Z","time spent":"331.331378ms","remote":"127.0.0.1:53826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T21:13:06.430263Z","caller":"traceutil/trace.go:171","msg":"trace[1878343599] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"154.653243ms","start":"2026-04-21T21:13:06.27558Z","end":"2026-04-21T21:13:06.430234Z","steps":["trace[1878343599] 'process raft request' (duration: 154.473468ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:13:07.11465Z","caller":"traceutil/trace.go:171","msg":"trace[1956699654] linearizableReadLoop","detail":"{readStateIndex:1279; appliedIndex:1278; }","duration":"300.649055ms","start":"2026-04-21T21:13:06.813982Z","end":"2026-04-21T21:13:07.114631Z","steps":["trace[1956699654] 'read index received' (duration: 300.445639ms)","trace[1956699654] 'applied index is now lower than readState.Index' (duration: 202.746µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:13:07.114844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.85094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-21T21:13:07.114871Z","caller":"traceutil/trace.go:171","msg":"trace[2087892285] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1234; }","duration":"300.920132ms","start":"2026-04-21T21:13:06.813945Z","end":"2026-04-21T21:13:07.114865Z","steps":["trace[2087892285] 'agreement among raft nodes before linearized reading' (duration: 300.767318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:13:07.114893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:13:06.813933Z","time spent":"300.954683ms","remote":"127.0.0.1:53826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-21T21:13:07.115028Z","caller":"traceutil/trace.go:171","msg":"trace[1191893841] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"315.009457ms","start":"2026-04-21T21:13:06.799985Z","end":"2026-04-21T21:13:07.114995Z","steps":["trace[1191893841] 'process raft request' (duration: 314.510993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:13:07.115223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:13:06.79996Z","time spent":"315.167731ms","remote":"127.0.0.1:53826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T21:13:07.346199Z","caller":"traceutil/trace.go:171","msg":"trace[692358975] linearizableReadLoop","detail":"{readStateIndex:1280; appliedIndex:1279; }","duration":"131.440975ms","start":"2026-04-21T21:13:07.214738Z","end":"2026-04-21T21:13:07.346179Z","steps":["trace[692358975] 'read index received' (duration: 113.399785ms)","trace[692358975] 'applied index is now lower than readState.Index' (duration: 18.04031ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T21:13:07.346407Z","caller":"traceutil/trace.go:171","msg":"trace[2069793808] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"226.429831ms","start":"2026-04-21T21:13:07.119967Z","end":"2026-04-21T21:13:07.346397Z","steps":["trace[2069793808] 'process raft request' (duration: 208.159935ms)","trace[2069793808] 'compare' (duration: 17.797173ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:13:07.346649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.905338ms","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-21T21:13:07.346683Z","caller":"traceutil/trace.go:171","msg":"trace[857338706] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1235; }","duration":"132.00722ms","start":"2026-04-21T21:13:07.21467Z","end":"2026-04-21T21:13:07.346677Z","steps":["trace[857338706] 'agreement among raft nodes before linearized reading' (duration: 131.885347ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:13:07.531862Z","caller":"traceutil/trace.go:171","msg":"trace[1974089543] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"192.140239ms","start":"2026-04-21T21:13:07.339692Z","end":"2026-04-21T21:13:07.531832Z","steps":["trace[1974089543] 'process raft request' (duration: 192.004695ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:13:07.531867Z","caller":"traceutil/trace.go:171","msg":"trace[1102030255] linearizableReadLoop","detail":"{readStateIndex:1281; appliedIndex:1281; }","duration":"185.566635ms","start":"2026-04-21T21:13:07.346262Z","end":"2026-04-21T21:13:07.531829Z","steps":["trace[1102030255] 'read index received' (duration: 185.549394ms)","trace[1102030255] 'applied index is now lower than readState.Index' (duration: 13.67µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T21:13:07.532212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.00119ms","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-21T21:13:07.532366Z","caller":"traceutil/trace.go:171","msg":"trace[293434152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1236; }","duration":"317.340519ms","start":"2026-04-21T21:13:07.214941Z","end":"2026-04-21T21:13:07.532282Z","steps":["trace[293434152] 'agreement among raft nodes before linearized reading' (duration: 316.99729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:13:07.532423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T21:13:07.214921Z","time spent":"317.490223ms","remote":"127.0.0.1:53826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-21T21:13:07.59358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.012669ms","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-21T21:13:07.593663Z","caller":"traceutil/trace.go:171","msg":"trace[912863382] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1237; }","duration":"150.166243ms","start":"2026-04-21T21:13:07.44348Z","end":"2026-04-21T21:13:07.593647Z","steps":["trace[912863382] 'agreement among raft nodes before linearized reading' (duration: 150.02673ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:13:07.593713Z","caller":"traceutil/trace.go:171","msg":"trace[272973041] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"238.582524ms","start":"2026-04-21T21:13:07.355064Z","end":"2026-04-21T21:13:07.593646Z","steps":["trace[272973041] 'process raft request' (duration: 238.230505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T21:13:07.593928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.181271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T21:13:07.59398Z","caller":"traceutil/trace.go:171","msg":"trace[21451292] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1237; }","duration":"101.275643ms","start":"2026-04-21T21:13:07.492684Z","end":"2026-04-21T21:13:07.59396Z","steps":["trace[21451292] 'agreement among raft nodes before linearized reading' (duration: 101.211801ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T21:13:22.125659Z","caller":"traceutil/trace.go:171","msg":"trace[444074100] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"120.599187ms","start":"2026-04-21T21:13:22.005033Z","end":"2026-04-21T21:13:22.125632Z","steps":["trace[444074100] 'process raft request' (duration: 120.442123ms)"],"step_count":1}