{"level":"warn","ts":"2026-02-01T18:46:35.634423Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-01T18:46:35.63455Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.205: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.205:2380","--initial-cluster=instance=https://199.204.45.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.205:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-01T18:46:35.635055Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-01T18:46:35.635072Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-02-01T18:46:35.63509Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-01T18:46:35.635685Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"]} {"level":"info","ts":"2026-02-01T18:46:35.643316Z","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":2,"max-cpu-available":2,"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.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.205:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-01T18:46:35.647551Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.911849ms"} {"level":"info","ts":"2026-02-01T18:46:35.681368Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"71d28e9ac1bc4e5d","cluster-id":"4a4ae660c7a9a6a7"} {"level":"info","ts":"2026-02-01T18:46:35.681428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=()"} {"level":"info","ts":"2026-02-01T18:46:35.681446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 0"} {"level":"info","ts":"2026-02-01T18:46:35.681457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 71d28e9ac1bc4e5d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-01T18:46:35.681463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 1"} {"level":"info","ts":"2026-02-01T18:46:35.681584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"warn","ts":"2026-02-01T18:46:35.687586Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-01T18:46:35.69183Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-01T18:46:35.693071Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-01T18:46:35.708039Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"71d28e9ac1bc4e5d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-01T18:46:35.711286Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"71d28e9ac1bc4e5d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-01T18:46:35.711389Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-01T18:46:35.711409Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-01T18:46:35.711418Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-01T18:46:35.713924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"info","ts":"2026-02-01T18:46:35.714207Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","added-peer-id":"71d28e9ac1bc4e5d","added-peer-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-02-01T18:46:35.725957Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-01T18:46:35.726364Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"71d28e9ac1bc4e5d","initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-01T18:46:35.726206Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-02-01T18:46:35.72731Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-02-01T18:46:35.72723Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-01T18:46:36.182383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d is starting a new election at term 1"} {"level":"info","ts":"2026-02-01T18:46:36.182508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became pre-candidate at term 1"} {"level":"info","ts":"2026-02-01T18:46:36.182576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgPreVoteResp from 71d28e9ac1bc4e5d at term 1"} {"level":"info","ts":"2026-02-01T18:46:36.182618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became candidate at term 2"} {"level":"info","ts":"2026-02-01T18:46:36.182663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgVoteResp from 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-02-01T18:46:36.1827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became leader at term 2"} {"level":"info","ts":"2026-02-01T18:46:36.182746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 71d28e9ac1bc4e5d elected leader 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-02-01T18:46:36.186782Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"71d28e9ac1bc4e5d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.205:2379]}","request-path":"/0/members/71d28e9ac1bc4e5d/attributes","cluster-id":"4a4ae660c7a9a6a7","publish-timeout":"7s"} {"level":"info","ts":"2026-02-01T18:46:36.18685Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-01T18:46:36.187319Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:46:36.190787Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:46:36.190884Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:46:36.190916Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-01T18:46:36.193346Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-01T18:46:36.193523Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:46:36.195047Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.205:2379"} {"level":"info","ts":"2026-02-01T18:46:36.195129Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-01T18:46:36.195153Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-02-01T18:46:50.602296Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646841145544254552,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-01T18:46:50.619796Z","caller":"traceutil/trace.go:171","msg":"trace[1506172915] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"710.571231ms","start":"2026-02-01T18:46:49.909205Z","end":"2026-02-01T18:46:50.619776Z","steps":["trace[1506172915] 'process raft request' (duration: 710.46166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:50.620387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:49.909192Z","time spent":"710.666542ms","remote":"127.0.0.1:32968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4245,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:46:50.621777Z","caller":"traceutil/trace.go:171","msg":"trace[261714697] linearizableReadLoop","detail":"{readStateIndex:383; appliedIndex:382; }","duration":"520.538295ms","start":"2026-02-01T18:46:50.101227Z","end":"2026-02-01T18:46:50.621765Z","steps":["trace[261714697] 'read index received' (duration: 518.366549ms)","trace[261714697] 'applied index is now lower than readState.Index' (duration: 2.170366ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:46:50.621858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.625397ms","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-02-01T18:46:50.621892Z","caller":"traceutil/trace.go:171","msg":"trace[168533048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:368; }","duration":"520.665037ms","start":"2026-02-01T18:46:50.101221Z","end":"2026-02-01T18:46:50.621886Z","steps":["trace[168533048] 'agreement among raft nodes before linearized reading' (duration: 520.603636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:50.621916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:50.101148Z","time spent":"520.762717ms","remote":"127.0.0.1:33048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T18:46:50.622256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.025862ms","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-02-01T18:46:50.622283Z","caller":"traceutil/trace.go:171","msg":"trace[549060390] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:368; }","duration":"319.106274ms","start":"2026-02-01T18:46:50.30317Z","end":"2026-02-01T18:46:50.622277Z","steps":["trace[549060390] 'agreement among raft nodes before linearized reading' (duration: 319.070093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:50.622302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:50.303152Z","time spent":"319.146224ms","remote":"127.0.0.1:33048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T18:46:50.622484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.828022ms","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-02-01T18:46:50.622509Z","caller":"traceutil/trace.go:171","msg":"trace[1467491659] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:368; }","duration":"378.917083ms","start":"2026-02-01T18:46:50.243587Z","end":"2026-02-01T18:46:50.622504Z","steps":["trace[1467491659] 'agreement among raft nodes before linearized reading' (duration: 378.877623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:50.62253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:50.243568Z","time spent":"378.957813ms","remote":"127.0.0.1:33048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T18:46:50.622658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.12072ms","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-02-01T18:46:50.622681Z","caller":"traceutil/trace.go:171","msg":"trace[1116890850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:368; }","duration":"514.620236ms","start":"2026-02-01T18:46:50.108055Z","end":"2026-02-01T18:46:50.622675Z","steps":["trace[1116890850] 'agreement among raft nodes before linearized reading' (duration: 514.14983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:50.622701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:50.108043Z","time spent":"514.653406ms","remote":"127.0.0.1:33048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T18:46:51.497711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.436889ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:46:51.498307Z","caller":"traceutil/trace.go:171","msg":"trace[456591781] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"214.100378ms","start":"2026-02-01T18:46:51.284183Z","end":"2026-02-01T18:46:51.498283Z","steps":["trace[456591781] 'process raft request' (duration: 214.045697ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:46:51.498702Z","caller":"traceutil/trace.go:171","msg":"trace[1934397537] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"865.590667ms","start":"2026-02-01T18:46:50.633097Z","end":"2026-02-01T18:46:51.498687Z","steps":["trace[1934397537] 'process raft request' (duration: 524.916813ms)","trace[1934397537] 'compare' (duration: 338.254956ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:46:51.498768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:50.633089Z","time spent":"865.640828ms","remote":"127.0.0.1:33048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:46:51.499052Z","caller":"traceutil/trace.go:171","msg":"trace[1319384676] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"865.732119ms","start":"2026-02-01T18:46:50.633307Z","end":"2026-02-01T18:46:51.499039Z","steps":["trace[1319384676] 'process raft request' (duration: 864.556806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:51.499113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:50.633301Z","time spent":"865.77606ms","remote":"127.0.0.1:33048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:46:51.499233Z","caller":"traceutil/trace.go:171","msg":"trace[1373588528] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"865.878061ms","start":"2026-02-01T18:46:50.633344Z","end":"2026-02-01T18:46:51.499222Z","steps":["trace[1373588528] 'process raft request' (duration: 864.672377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:51.499312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:50.63334Z","time spent":"865.914472ms","remote":"127.0.0.1:33048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:46:51.499472Z","caller":"traceutil/trace.go:171","msg":"trace[2068553239] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"866.075024ms","start":"2026-02-01T18:46:50.633384Z","end":"2026-02-01T18:46:51.499459Z","steps":["trace[2068553239] 'process raft request' (duration: 864.735068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:51.499692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:50.633381Z","time spent":"866.154265ms","remote":"127.0.0.1:33048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:46:51.500031Z","caller":"traceutil/trace.go:171","msg":"trace[385370415] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"863.821798ms","start":"2026-02-01T18:46:50.636195Z","end":"2026-02-01T18:46:51.500017Z","steps":["trace[385370415] 'process raft request' (duration: 861.972145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:51.500108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:50.636188Z","time spent":"863.874658ms","remote":"127.0.0.1:32968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4053,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:46:51.50027Z","caller":"traceutil/trace.go:171","msg":"trace[720053113] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:383; }","duration":"495.976974ms","start":"2026-02-01T18:46:51.00428Z","end":"2026-02-01T18:46:51.500257Z","steps":["trace[720053113] 'read index received' (duration: 153.68512ms)","trace[720053113] 'applied index is now lower than readState.Index' (duration: 342.290354ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:46:51.500342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.056794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4013"} {"level":"info","ts":"2026-02-01T18:46:51.500369Z","caller":"traceutil/trace.go:171","msg":"trace[1375910881] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:374; }","duration":"496.154306ms","start":"2026-02-01T18:46:51.004204Z","end":"2026-02-01T18:46:51.500358Z","steps":["trace[1375910881] 'agreement among raft nodes before linearized reading' (duration: 496.091345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:51.500398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:51.004185Z","time spent":"496.207136ms","remote":"127.0.0.1:32958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4035,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-01T18:46:59.846524Z","caller":"traceutil/trace.go:171","msg":"trace[1153660952] linearizableReadLoop","detail":"{readStateIndex:441; appliedIndex:440; }","duration":"304.112448ms","start":"2026-02-01T18:46:59.542385Z","end":"2026-02-01T18:46:59.846497Z","steps":["trace[1153660952] 'read index received' (duration: 303.913256ms)","trace[1153660952] 'applied index is now lower than readState.Index' (duration: 197.842µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:46:59.846855Z","caller":"traceutil/trace.go:171","msg":"trace[1741126386] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"304.601264ms","start":"2026-02-01T18:46:59.542242Z","end":"2026-02-01T18:46:59.846844Z","steps":["trace[1741126386] 'process raft request' (duration: 304.125978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:59.846966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:59.542228Z","time spent":"304.667156ms","remote":"127.0.0.1:33048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:46:59.847174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.782977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-01T18:46:59.847208Z","caller":"traceutil/trace.go:171","msg":"trace[2081438222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:424; }","duration":"304.833798ms","start":"2026-02-01T18:46:59.542364Z","end":"2026-02-01T18:46:59.847197Z","steps":["trace[2081438222] 'agreement among raft nodes before linearized reading' (duration: 304.760447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:59.847236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:59.542358Z","time spent":"304.871578ms","remote":"127.0.0.1:33048","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":"warn","ts":"2026-02-01T18:46:59.847435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.003461ms","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-02-01T18:46:59.847462Z","caller":"traceutil/trace.go:171","msg":"trace[684911443] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:424; }","duration":"305.038531ms","start":"2026-02-01T18:46:59.542415Z","end":"2026-02-01T18:46:59.847454Z","steps":["trace[684911443] 'agreement among raft nodes before linearized reading' (duration: 304.93327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:59.847486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:59.542411Z","time spent":"305.069741ms","remote":"127.0.0.1:33048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T18:46:59.84767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.646353ms","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-02-01T18:46:59.847699Z","caller":"traceutil/trace.go:171","msg":"trace[1650925369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:424; }","duration":"303.688553ms","start":"2026-02-01T18:46:59.544003Z","end":"2026-02-01T18:46:59.847692Z","steps":["trace[1650925369] 'agreement among raft nodes before linearized reading' (duration: 303.640673ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:59.847724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:59.543996Z","time spent":"303.722544ms","remote":"127.0.0.1:33048","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":"info","ts":"2026-02-01T18:47:14.189069Z","caller":"traceutil/trace.go:171","msg":"trace[1500352320] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:536; }","duration":"247.249806ms","start":"2026-02-01T18:47:13.941804Z","end":"2026-02-01T18:47:14.189054Z","steps":["trace[1500352320] 'read index received' (duration: 247.088083ms)","trace[1500352320] 'applied index is now lower than readState.Index' (duration: 161.183µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:47:14.189149Z","caller":"traceutil/trace.go:171","msg":"trace[183511368] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"258.404998ms","start":"2026-02-01T18:47:13.930739Z","end":"2026-02-01T18:47:14.189144Z","steps":["trace[183511368] 'process raft request' (duration: 258.205785ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:14.189292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.473559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-01T18:47:14.189308Z","caller":"traceutil/trace.go:171","msg":"trace[947382707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:518; }","duration":"247.514689ms","start":"2026-02-01T18:47:13.941787Z","end":"2026-02-01T18:47:14.189302Z","steps":["trace[947382707] 'agreement among raft nodes before linearized reading' (duration: 247.438268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:14.189376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.523769ms","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-02-01T18:47:14.189387Z","caller":"traceutil/trace.go:171","msg":"trace[1505752726] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:518; }","duration":"225.561039ms","start":"2026-02-01T18:47:13.963823Z","end":"2026-02-01T18:47:14.189384Z","steps":["trace[1505752726] 'agreement among raft nodes before linearized reading' (duration: 225.527058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:14.625209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.87814ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:47:14.625273Z","caller":"traceutil/trace.go:171","msg":"trace[904787658] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"432.023771ms","start":"2026-02-01T18:47:14.193236Z","end":"2026-02-01T18:47:14.62526Z","steps":["trace[904787658] 'process raft request' (duration: 188.044889ms)","trace[904787658] 'compare' (duration: 243.809959ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:47:14.625303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:14.193224Z","time spent":"432.065741ms","remote":"127.0.0.1:33048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:47:14.912563Z","caller":"traceutil/trace.go:171","msg":"trace[768958661] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"719.247669ms","start":"2026-02-01T18:47:14.193298Z","end":"2026-02-01T18:47:14.912545Z","steps":["trace[768958661] 'process raft request' (duration: 676.844586ms)","trace[768958661] 'compare' (duration: 42.270831ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:47:14.912649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:14.193293Z","time spent":"719.31173ms","remote":"127.0.0.1:33048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:47:14.912812Z","caller":"traceutil/trace.go:171","msg":"trace[1187078402] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"718.890093ms","start":"2026-02-01T18:47:14.193917Z","end":"2026-02-01T18:47:14.912807Z","steps":["trace[1187078402] 'process raft request' (duration: 718.568978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:14.912872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:14.193912Z","time spent":"718.912313ms","remote":"127.0.0.1:33048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:47:14.912977Z","caller":"traceutil/trace.go:171","msg":"trace[700692444] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:537; }","duration":"541.109536ms","start":"2026-02-01T18:47:14.371862Z","end":"2026-02-01T18:47:14.912971Z","steps":["trace[700692444] 'read index received' (duration: 13.37959ms)","trace[700692444] 'applied index is now lower than readState.Index' (duration: 527.729236ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:47:14.975395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.514311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4xdws\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-01T18:47:14.975448Z","caller":"traceutil/trace.go:171","msg":"trace[1080210667] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4xdws; range_end:; response_count:1; response_revision:521; }","duration":"603.600802ms","start":"2026-02-01T18:47:14.371833Z","end":"2026-02-01T18:47:14.975434Z","steps":["trace[1080210667] 'agreement among raft nodes before linearized reading' (duration: 541.156746ms)","trace[1080210667] 'range keys from in-memory index tree' (duration: 62.318213ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:47:14.975474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:14.371819Z","time spent":"603.649652ms","remote":"127.0.0.1:32968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16678,"request content":"key:\"/registry/pods/kube-system/cilium-4xdws\" "} {"level":"info","ts":"2026-02-01T18:47:15.24122Z","caller":"traceutil/trace.go:171","msg":"trace[1180835317] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"255.358325ms","start":"2026-02-01T18:47:14.985846Z","end":"2026-02-01T18:47:15.241204Z","steps":["trace[1180835317] 'process raft request' (duration: 255.219263ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:16.514488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.225257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-01T18:47:16.514547Z","caller":"traceutil/trace.go:171","msg":"trace[2093358851] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:522; }","duration":"321.322528ms","start":"2026-02-01T18:47:16.19321Z","end":"2026-02-01T18:47:16.514532Z","steps":["trace[2093358851] 'range keys from in-memory index tree' (duration: 321.106795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:16.514597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:16.193198Z","time spent":"321.372559ms","remote":"127.0.0.1:33048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T18:47:16.515117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049789029s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:47:16.515149Z","caller":"traceutil/trace.go:171","msg":"trace[2064477872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:522; }","duration":"1.04984079s","start":"2026-02-01T18:47:15.465293Z","end":"2026-02-01T18:47:16.515133Z","steps":["trace[2064477872] 'range keys from in-memory index tree' (duration: 1.049736739s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:16.51517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:15.465277Z","time spent":"1.049887981s","remote":"127.0.0.1:60992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-01T18:47:16.658985Z","caller":"traceutil/trace.go:171","msg":"trace[1719794431] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:542; }","duration":"136.282898ms","start":"2026-02-01T18:47:16.522652Z","end":"2026-02-01T18:47:16.658934Z","steps":["trace[1719794431] 'read index received' (duration: 136.159716ms)","trace[1719794431] 'applied index is now lower than readState.Index' (duration: 122.652µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:47:16.659145Z","caller":"traceutil/trace.go:171","msg":"trace[578353382] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"140.783479ms","start":"2026-02-01T18:47:16.518356Z","end":"2026-02-01T18:47:16.65914Z","steps":["trace[578353382] 'process raft request' (duration: 140.497444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:16.65927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.603493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:47:16.659294Z","caller":"traceutil/trace.go:171","msg":"trace[543001616] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"136.652704ms","start":"2026-02-01T18:47:16.522634Z","end":"2026-02-01T18:47:16.659287Z","steps":["trace[543001616] 'agreement among raft nodes before linearized reading' (duration: 136.606423ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:17.33494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.010541ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:47:17.335036Z","caller":"traceutil/trace.go:171","msg":"trace[57078719] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:543; }","duration":"405.263233ms","start":"2026-02-01T18:47:16.929756Z","end":"2026-02-01T18:47:17.33502Z","steps":["trace[57078719] 'read index received' (duration: 37.047918ms)","trace[57078719] 'applied index is now lower than readState.Index' (duration: 368.213935ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:47:17.335115Z","caller":"traceutil/trace.go:171","msg":"trace[477504162] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"672.526105ms","start":"2026-02-01T18:47:16.662567Z","end":"2026-02-01T18:47:17.335093Z","steps":["trace[477504162] 'process raft request' (duration: 304.28503ms)","trace[477504162] 'compare' (duration: 367.813578ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:47:17.335193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.431805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-01T18:47:17.335214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:16.662553Z","time spent":"672.603806ms","remote":"127.0.0.1:33048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:47:17.335243Z","caller":"traceutil/trace.go:171","msg":"trace[750770912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:524; }","duration":"405.485956ms","start":"2026-02-01T18:47:16.929729Z","end":"2026-02-01T18:47:17.335215Z","steps":["trace[750770912] 'agreement among raft nodes before linearized reading' (duration: 405.369994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:17.335277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:16.929716Z","time spent":"405.553327ms","remote":"127.0.0.1:33048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T18:47:17.335415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.536626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-01T18:47:17.335436Z","caller":"traceutil/trace.go:171","msg":"trace[902217622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:524; }","duration":"405.577407ms","start":"2026-02-01T18:47:16.929852Z","end":"2026-02-01T18:47:17.335429Z","steps":["trace[902217622] 'agreement among raft nodes before linearized reading' (duration: 405.503306ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:17.335451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:16.929849Z","time spent":"405.598887ms","remote":"127.0.0.1:33048","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-02-01T18:47:18.908281Z","caller":"traceutil/trace.go:171","msg":"trace[1573676327] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"241.988833ms","start":"2026-02-01T18:47:18.666276Z","end":"2026-02-01T18:47:18.908265Z","steps":["trace[1573676327] 'process raft request' (duration: 241.893741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:21.833086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.814868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-01T18:47:21.833172Z","caller":"traceutil/trace.go:171","msg":"trace[1196232416] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:592; }","duration":"153.94957ms","start":"2026-02-01T18:47:21.679204Z","end":"2026-02-01T18:47:21.833154Z","steps":["trace[1196232416] 'range keys from in-memory index tree' (duration: 153.619335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:35.301743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.793762ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-01T18:48:35.301864Z","caller":"traceutil/trace.go:171","msg":"trace[416044822] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:963; }","duration":"448.584933ms","start":"2026-02-01T18:48:34.853262Z","end":"2026-02-01T18:48:35.301847Z","steps":["trace[416044822] 'read index received' (duration: 283.614188ms)","trace[416044822] 'applied index is now lower than readState.Index' (duration: 164.968815ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:48:35.302183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.892279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T18:48:35.302223Z","caller":"traceutil/trace.go:171","msg":"trace[1266071856] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:924; }","duration":"448.983621ms","start":"2026-02-01T18:48:34.853224Z","end":"2026-02-01T18:48:35.302208Z","steps":["trace[1266071856] 'agreement among raft nodes before linearized reading' (duration: 448.890199ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:35.302271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:34.853208Z","time spent":"449.053061ms","remote":"127.0.0.1:57642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-02-01T18:48:42.281064Z","caller":"traceutil/trace.go:171","msg":"trace[984502376] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"124.271067ms","start":"2026-02-01T18:48:42.156768Z","end":"2026-02-01T18:48:42.281039Z","steps":["trace[984502376] 'process raft request' (duration: 124.030533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:42.925587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.788988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:48:42.925677Z","caller":"traceutil/trace.go:171","msg":"trace[1533850216] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:952; }","duration":"458.92273ms","start":"2026-02-01T18:48:42.466731Z","end":"2026-02-01T18:48:42.925654Z","steps":["trace[1533850216] 'range keys from in-memory index tree' (duration: 458.718667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:42.925718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:42.466717Z","time spent":"458.991572ms","remote":"127.0.0.1:60992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-01T18:48:44.112579Z","caller":"traceutil/trace.go:171","msg":"trace[1640308388] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:994; }","duration":"320.050877ms","start":"2026-02-01T18:48:43.792504Z","end":"2026-02-01T18:48:44.112555Z","steps":["trace[1640308388] 'read index received' (duration: 319.748831ms)","trace[1640308388] 'applied index is now lower than readState.Index' (duration: 301.346µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:48:44.112885Z","caller":"traceutil/trace.go:171","msg":"trace[2117497629] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"418.613771ms","start":"2026-02-01T18:48:43.694141Z","end":"2026-02-01T18:48:44.112755Z","steps":["trace[2117497629] 'process raft request' (duration: 418.188314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:44.112985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:43.694122Z","time spent":"418.785195ms","remote":"127.0.0.1:32958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5218,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:48:44.113116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.571012ms","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-02-01T18:48:44.113223Z","caller":"traceutil/trace.go:171","msg":"trace[2039133867] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:954; }","duration":"312.726294ms","start":"2026-02-01T18:48:43.800474Z","end":"2026-02-01T18:48:44.1132Z","steps":["trace[2039133867] 'agreement among raft nodes before linearized reading' (duration: 312.381188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:44.11327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:43.800464Z","time spent":"312.796815ms","remote":"127.0.0.1:33048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-01T18:48:44.113515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.009318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-01T18:48:44.113569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.460203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-01T18:48:44.113602Z","caller":"traceutil/trace.go:171","msg":"trace[1340580882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:954; }","duration":"144.537494ms","start":"2026-02-01T18:48:43.969055Z","end":"2026-02-01T18:48:44.113592Z","steps":["trace[1340580882] 'agreement among raft nodes before linearized reading' (duration: 144.450692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:44.113955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.454982ms","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-02-01T18:48:44.113985Z","caller":"traceutil/trace.go:171","msg":"trace[2006431447] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:954; }","duration":"321.485283ms","start":"2026-02-01T18:48:43.792491Z","end":"2026-02-01T18:48:44.113976Z","steps":["trace[2006431447] 'agreement among raft nodes before linearized reading' (duration: 321.401681ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:44.114016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:43.792421Z","time spent":"321.588864ms","remote":"127.0.0.1:33048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-01T18:48:44.113548Z","caller":"traceutil/trace.go:171","msg":"trace[452028273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:954; }","duration":"142.05437ms","start":"2026-02-01T18:48:43.971483Z","end":"2026-02-01T18:48:44.113537Z","steps":["trace[452028273] 'agreement among raft nodes before linearized reading' (duration: 141.926968ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:44.277113Z","caller":"traceutil/trace.go:171","msg":"trace[1676655385] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"147.118803ms","start":"2026-02-01T18:48:44.12968Z","end":"2026-02-01T18:48:44.276798Z","steps":["trace[1676655385] 'process raft request' (duration: 66.478498ms)","trace[1676655385] 'compare' (duration: 80.523892ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:48:44.304044Z","caller":"traceutil/trace.go:171","msg":"trace[872327541] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"168.709931ms","start":"2026-02-01T18:48:44.135322Z","end":"2026-02-01T18:48:44.304032Z","steps":["trace[872327541] 'process raft request' (duration: 168.442406ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:44.304582Z","caller":"traceutil/trace.go:171","msg":"trace[889917655] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"169.1603ms","start":"2026-02-01T18:48:44.135409Z","end":"2026-02-01T18:48:44.304569Z","steps":["trace[889917655] 'process raft request' (duration: 168.426876ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:44.306081Z","caller":"traceutil/trace.go:171","msg":"trace[1544824360] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"170.634285ms","start":"2026-02-01T18:48:44.135436Z","end":"2026-02-01T18:48:44.30607Z","steps":["trace[1544824360] 'process raft request' (duration: 168.427426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:45.542935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074865451s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:48:45.543024Z","caller":"traceutil/trace.go:171","msg":"trace[1689357129] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:958; }","duration":"1.074991703s","start":"2026-02-01T18:48:44.468002Z","end":"2026-02-01T18:48:45.542994Z","steps":["trace[1689357129] 'range keys from in-memory index tree' (duration: 1.074732589s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:45.543053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:44.467982Z","time spent":"1.075065144s","remote":"127.0.0.1:32770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-01T18:48:45.544087Z","caller":"traceutil/trace.go:171","msg":"trace[1528204427] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"1.000577651s","start":"2026-02-01T18:48:44.543493Z","end":"2026-02-01T18:48:45.544071Z","steps":["trace[1528204427] 'process raft request' (duration: 918.122164ms)","trace[1528204427] 'compare' (duration: 82.093951ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:48:45.544156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:44.543471Z","time spent":"1.000639173s","remote":"127.0.0.1:33048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:48:45.571815Z","caller":"traceutil/trace.go:171","msg":"trace[2122887861] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:999; }","duration":"115.755267ms","start":"2026-02-01T18:48:45.456035Z","end":"2026-02-01T18:48:45.571791Z","steps":["trace[2122887861] 'read index received' (duration: 5.58722ms)","trace[2122887861] 'applied index is now lower than readState.Index' (duration: 110.166637ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:48:45.572179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.092493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T18:48:45.574171Z","caller":"traceutil/trace.go:171","msg":"trace[1311820842] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:959; }","duration":"118.153261ms","start":"2026-02-01T18:48:45.455991Z","end":"2026-02-01T18:48:45.574144Z","steps":["trace[1311820842] 'agreement among raft nodes before linearized reading' (duration: 116.049733ms)"],"step_count":1}