{"level":"warn","ts":"2026-02-24T08:07:35.814131Z","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-24T08:07:35.81423Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.233: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.233:2380","--initial-cluster=instance=https://199.204.45.233:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.233:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.233: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-24T08:07:35.8143Z","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-24T08:07:35.814424Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-02-24T08:07:35.814466Z","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-24T08:07:35.815587Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"]} {"level":"info","ts":"2026-02-24T08:07:35.81572Z","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.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.233: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-24T08:07:35.820252Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.272949ms"} {"level":"info","ts":"2026-02-24T08:07:35.829941Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"90a0cb2044183f95","cluster-id":"782fef34d5ea94f9"} {"level":"info","ts":"2026-02-24T08:07:35.830068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=()"} {"level":"info","ts":"2026-02-24T08:07:35.830135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 0"} {"level":"info","ts":"2026-02-24T08:07:35.830173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 90a0cb2044183f95 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-24T08:07:35.830215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 1"} {"level":"info","ts":"2026-02-24T08:07:35.830287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"warn","ts":"2026-02-24T08:07:35.836933Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-24T08:07:35.839006Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-24T08:07:35.841514Z","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-24T08:07:35.843709Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"90a0cb2044183f95","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-24T08:07:35.84524Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"90a0cb2044183f95","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-24T08:07:35.845243Z","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-24T08:07:35.845998Z","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-24T08:07:35.846039Z","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-24T08:07:35.846422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"info","ts":"2026-02-24T08:07:35.846756Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","added-peer-id":"90a0cb2044183f95","added-peer-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-02-24T08:07:35.849082Z","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-24T08:07:35.849562Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"90a0cb2044183f95","initial-advertise-peer-urls":["https://199.204.45.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-24T08:07:35.849627Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-24T08:07:35.85119Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-02-24T08:07:35.851257Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-02-24T08:07:36.630753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 is starting a new election at term 1"} {"level":"info","ts":"2026-02-24T08:07:36.630814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-24T08:07:36.63084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgPreVoteResp from 90a0cb2044183f95 at term 1"} {"level":"info","ts":"2026-02-24T08:07:36.630853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became candidate at term 2"} {"level":"info","ts":"2026-02-24T08:07:36.63086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgVoteResp from 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-02-24T08:07:36.630868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became leader at term 2"} {"level":"info","ts":"2026-02-24T08:07:36.630876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 90a0cb2044183f95 elected leader 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-02-24T08:07:36.631821Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T08:07:36.632358Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T08:07:36.632433Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T08:07:36.632894Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T08:07:36.633008Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T08:07:36.633031Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T08:07:36.632347Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"90a0cb2044183f95","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.233:2379]}","request-path":"/0/members/90a0cb2044183f95/attributes","cluster-id":"782fef34d5ea94f9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-24T08:07:36.635685Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-24T08:07:36.635842Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-24T08:07:36.637563Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.233:2379"} {"level":"info","ts":"2026-02-24T08:07:36.640638Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-24T08:07:44.937324Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740332579330216,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T08:07:45.315026Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.051102862s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T08:07:45.315444Z","caller":"traceutil/trace.go:171","msg":"trace[1900504792] linearizableReadLoop","detail":"{readStateIndex:298; appliedIndex:297; }","duration":"878.343089ms","start":"2026-02-24T08:07:44.437075Z","end":"2026-02-24T08:07:45.315418Z","steps":["trace[1900504792] 'read index received' (duration: 878.139854ms)","trace[1900504792] 'applied index is now lower than readState.Index' (duration: 202.115µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:07:45.315535Z","caller":"traceutil/trace.go:171","msg":"trace[1542383385] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"1.051744677s","start":"2026-02-24T08:07:44.263781Z","end":"2026-02-24T08:07:45.315525Z","steps":["trace[1542383385] 'process raft request' (duration: 1.051489451s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:07:45.316041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:07:44.263742Z","time spent":"1.051827549s","remote":"127.0.0.1:40644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":186,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T08:07:45.316121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.997344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-24T08:07:45.316085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.428741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-24T08:07:45.316243Z","caller":"traceutil/trace.go:171","msg":"trace[876374054] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:288; }","duration":"879.200928ms","start":"2026-02-24T08:07:44.437016Z","end":"2026-02-24T08:07:45.316217Z","steps":["trace[876374054] 'agreement among raft nodes before linearized reading' (duration: 878.663016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:07:45.316306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:07:44.436981Z","time spent":"879.315151ms","remote":"127.0.0.1:40706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-24T08:07:45.316507Z","caller":"traceutil/trace.go:171","msg":"trace[1381270111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:288; }","duration":"287.928503ms","start":"2026-02-24T08:07:45.028554Z","end":"2026-02-24T08:07:45.316482Z","steps":["trace[1381270111] 'agreement among raft nodes before linearized reading' (duration: 287.116014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:07:55.760631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.178521ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T08:07:55.760773Z","caller":"traceutil/trace.go:171","msg":"trace[1181138445] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"315.118415ms","start":"2026-02-24T08:07:55.445626Z","end":"2026-02-24T08:07:55.760744Z","steps":["trace[1181138445] 'process raft request' (duration: 34.293691ms)","trace[1181138445] 'compare' (duration: 280.025427ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T08:07:55.760857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:07:55.445614Z","time spent":"315.201746ms","remote":"127.0.0.1:40706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T08:08:07.034676Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740332579330579,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T08:08:07.195803Z","caller":"traceutil/trace.go:171","msg":"trace[1281165910] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"762.926399ms","start":"2026-02-24T08:08:06.432852Z","end":"2026-02-24T08:08:07.195778Z","steps":["trace[1281165910] 'process raft request' (duration: 762.47845ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:08:07.195913Z","caller":"traceutil/trace.go:171","msg":"trace[826381611] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"756.913979ms","start":"2026-02-24T08:08:06.43894Z","end":"2026-02-24T08:08:07.195854Z","steps":["trace[826381611] 'process raft request' (duration: 756.712215ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:08:07.195991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:08:06.432843Z","time spent":"763.042321ms","remote":"127.0.0.1:40560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317259,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T08:08:07.196158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:08:06.438923Z","time spent":"757.114633ms","remote":"127.0.0.1:40620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16641,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T08:08:07.196365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.880525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14309"} {"level":"info","ts":"2026-02-24T08:08:07.196415Z","caller":"traceutil/trace.go:171","msg":"trace[1502583681] linearizableReadLoop","detail":"{readStateIndex:483; appliedIndex:481; }","duration":"661.354284ms","start":"2026-02-24T08:08:06.534368Z","end":"2026-02-24T08:08:07.195722Z","steps":["trace[1502583681] 'read index received' (duration: 660.849884ms)","trace[1502583681] 'applied index is now lower than readState.Index' (duration: 503.26µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T08:08:07.196643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.162527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"warn","ts":"2026-02-24T08:08:07.196665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.221659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-02-24T08:08:07.196684Z","caller":"traceutil/trace.go:171","msg":"trace[1059750732] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:468; }","duration":"556.29311ms","start":"2026-02-24T08:08:06.640378Z","end":"2026-02-24T08:08:07.196671Z","steps":["trace[1059750732] 'agreement among raft nodes before linearized reading' (duration: 556.122907ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:08:07.196718Z","caller":"traceutil/trace.go:171","msg":"trace[1944671897] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:468; }","duration":"555.298011ms","start":"2026-02-24T08:08:06.641401Z","end":"2026-02-24T08:08:07.196699Z","steps":["trace[1944671897] 'agreement among raft nodes before linearized reading' (duration: 555.169208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:08:07.196723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:08:06.640352Z","time spent":"556.362642ms","remote":"127.0.0.1:40644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":543,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"warn","ts":"2026-02-24T08:08:07.196755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:08:06.641388Z","time spent":"555.360572ms","remote":"127.0.0.1:40644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":561,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" "} {"level":"info","ts":"2026-02-24T08:08:07.196574Z","caller":"traceutil/trace.go:171","msg":"trace[801145967] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:468; }","duration":"662.13641ms","start":"2026-02-24T08:08:06.534317Z","end":"2026-02-24T08:08:07.196453Z","steps":["trace[801145967] 'agreement among raft nodes before linearized reading' (duration: 661.576629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:08:07.197318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:08:06.534294Z","time spent":"662.904555ms","remote":"127.0.0.1:40930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":14332,"request content":"key:\"/registry/daemonsets/kube-system/cilium\" "} {"level":"info","ts":"2026-02-24T08:08:11.929385Z","caller":"traceutil/trace.go:171","msg":"trace[1738785286] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"207.18867ms","start":"2026-02-24T08:08:11.722162Z","end":"2026-02-24T08:08:11.929351Z","steps":["trace[1738785286] 'process raft request' (duration: 206.898025ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:08:15.949599Z","caller":"traceutil/trace.go:171","msg":"trace[189951056] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"131.317824ms","start":"2026-02-24T08:08:15.818242Z","end":"2026-02-24T08:08:15.94956Z","steps":["trace[189951056] 'process raft request' (duration: 131.011428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:08:16.36498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.299067ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T08:08:16.365158Z","caller":"traceutil/trace.go:171","msg":"trace[1799231194] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:534; }","duration":"250.875178ms","start":"2026-02-24T08:08:16.114266Z","end":"2026-02-24T08:08:16.365141Z","steps":["trace[1799231194] 'read index received' (duration: 86.232µs)","trace[1799231194] 'applied index is now lower than readState.Index' (duration: 250.787836ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:08:16.365261Z","caller":"traceutil/trace.go:171","msg":"trace[1495953028] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"411.500049ms","start":"2026-02-24T08:08:15.953745Z","end":"2026-02-24T08:08:16.365245Z","steps":["trace[1495953028] 'process raft request' (duration: 146.539499ms)","trace[1495953028] 'compare' (duration: 264.128174ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T08:08:16.365318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:08:15.953727Z","time spent":"411.559389ms","remote":"127.0.0.1:40706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T08:08:16.36543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.137202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T08:08:16.365662Z","caller":"traceutil/trace.go:171","msg":"trace[374251956] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"409.862537ms","start":"2026-02-24T08:08:15.955749Z","end":"2026-02-24T08:08:16.365611Z","steps":["trace[374251956] 'process raft request' (duration: 409.332478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:08:16.365705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.581179ms","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-24T08:08:16.365712Z","caller":"traceutil/trace.go:171","msg":"trace[983063845] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:519; }","duration":"251.320176ms","start":"2026-02-24T08:08:16.114203Z","end":"2026-02-24T08:08:16.365523Z","steps":["trace[983063845] 'agreement among raft nodes before linearized reading' (duration: 251.067931ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:08:16.365754Z","caller":"traceutil/trace.go:171","msg":"trace[1560712111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:519; }","duration":"208.666761ms","start":"2026-02-24T08:08:16.157075Z","end":"2026-02-24T08:08:16.365742Z","steps":["trace[1560712111] 'agreement among raft nodes before linearized reading' (duration: 208.445917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:08:16.365888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:08:15.955731Z","time spent":"410.02179ms","remote":"127.0.0.1:40706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T08:08:17.481545Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.110589958s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T08:08:17.481935Z","caller":"traceutil/trace.go:171","msg":"trace[1133212086] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"1.111080387s","start":"2026-02-24T08:08:16.370831Z","end":"2026-02-24T08:08:17.481912Z","steps":["trace[1133212086] 'process raft request' (duration: 1.110895903s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:08:17.482096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:08:16.370815Z","time spent":"1.111176809s","remote":"127.0.0.1:40706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T08:08:18.61403Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740332579330702,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T08:08:18.900762Z","caller":"traceutil/trace.go:171","msg":"trace[104118019] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:537; }","duration":"787.304454ms","start":"2026-02-24T08:08:18.113425Z","end":"2026-02-24T08:08:18.900729Z","steps":["trace[104118019] 'read index received' (duration: 787.118631ms)","trace[104118019] 'applied index is now lower than readState.Index' (duration: 184.743µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:08:18.9008Z","caller":"traceutil/trace.go:171","msg":"trace[2004375878] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"921.361405ms","start":"2026-02-24T08:08:17.97941Z","end":"2026-02-24T08:08:18.900771Z","steps":["trace[2004375878] 'process raft request' (duration: 921.156761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:08:18.900933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.486497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T08:08:18.900971Z","caller":"traceutil/trace.go:171","msg":"trace[1175602374] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:521; }","duration":"787.58129ms","start":"2026-02-24T08:08:18.113379Z","end":"2026-02-24T08:08:18.90096Z","steps":["trace[1175602374] 'agreement among raft nodes before linearized reading' (duration: 787.486698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:08:18.90098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:08:17.979387Z","time spent":"921.492857ms","remote":"127.0.0.1:40706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T08:08:18.901017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:08:18.113347Z","time spent":"787.66115ms","remote":"127.0.0.1:40458","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-02-24T08:08:18.93135Z","caller":"traceutil/trace.go:171","msg":"trace[434491842] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"303.319465ms","start":"2026-02-24T08:08:18.628013Z","end":"2026-02-24T08:08:18.931333Z","steps":["trace[434491842] 'process raft request' (duration: 303.0624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:08:18.931468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:08:18.627982Z","time spent":"303.414286ms","remote":"127.0.0.1:40706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T08:08:18.931444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.458047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-24T08:08:18.931499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.488468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T08:08:18.931586Z","caller":"traceutil/trace.go:171","msg":"trace[1291500514] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:522; }","duration":"561.65686ms","start":"2026-02-24T08:08:18.369907Z","end":"2026-02-24T08:08:18.931564Z","steps":["trace[1291500514] 'agreement among raft nodes before linearized reading' (duration: 561.367705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:08:18.931634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:08:18.369883Z","time spent":"561.740872ms","remote":"127.0.0.1:40706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-24T08:08:18.931529Z","caller":"traceutil/trace.go:171","msg":"trace[1597014627] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:522; }","duration":"561.594169ms","start":"2026-02-24T08:08:18.369907Z","end":"2026-02-24T08:08:18.931501Z","steps":["trace[1597014627] 'agreement among raft nodes before linearized reading' (duration: 561.387915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:08:18.931724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:08:18.369883Z","time spent":"561.833614ms","remote":"127.0.0.1:40706","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-24T08:08:19.627546Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740332579330707,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T08:08:20.07995Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.067170756s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-24T08:08:20.085384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.071897852s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T08:08:20.085583Z","caller":"traceutil/trace.go:171","msg":"trace[235861266] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:539; }","duration":"959.146ms","start":"2026-02-24T08:08:19.126424Z","end":"2026-02-24T08:08:20.08557Z","steps":["trace[235861266] 'read index received' (duration: 953.66792ms)","trace[235861266] 'applied index is now lower than readState.Index' (duration: 5.47744ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:08:20.085644Z","caller":"traceutil/trace.go:171","msg":"trace[1860100867] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"1.12072398s","start":"2026-02-24T08:08:18.964915Z","end":"2026-02-24T08:08:20.085639Z","steps":["trace[1860100867] 'process raft request' (duration: 1.120568777s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:08:20.085686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:08:18.964881Z","time spent":"1.120785261s","remote":"127.0.0.1:40706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T08:08:20.08569Z","caller":"traceutil/trace.go:171","msg":"trace[874837464] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"1.121320711s","start":"2026-02-24T08:08:18.964345Z","end":"2026-02-24T08:08:20.085665Z","steps":["trace[874837464] 'process raft request' (duration: 48.528513ms)","trace[874837464] 'compare' (duration: 1.071692938s)"],"step_count":2} {"level":"warn","ts":"2026-02-24T08:08:20.085768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:08:18.964319Z","time spent":"1.121407762s","remote":"127.0.0.1:40706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T08:08:20.085817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.152366ms","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-24T08:08:20.085823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.564475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-24T08:08:20.085891Z","caller":"traceutil/trace.go:171","msg":"trace[1332006207] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:524; }","duration":"409.666906ms","start":"2026-02-24T08:08:19.676207Z","end":"2026-02-24T08:08:20.085874Z","steps":["trace[1332006207] 'agreement among raft nodes before linearized reading' (duration: 409.522093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:08:20.08585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.368303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T08:08:20.085889Z","caller":"traceutil/trace.go:171","msg":"trace[964714575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:524; }","duration":"597.277599ms","start":"2026-02-24T08:08:19.488591Z","end":"2026-02-24T08:08:20.085868Z","steps":["trace[964714575] 'agreement among raft nodes before linearized reading' (duration: 597.084625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:08:20.085928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:08:19.676182Z","time spent":"409.737738ms","remote":"127.0.0.1:40512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.233\" "} {"level":"warn","ts":"2026-02-24T08:08:20.085948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:08:19.488565Z","time spent":"597.37456ms","remote":"127.0.0.1:40706","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":"info","ts":"2026-02-24T08:08:20.085948Z","caller":"traceutil/trace.go:171","msg":"trace[1930064641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:524; }","duration":"959.536066ms","start":"2026-02-24T08:08:19.126393Z","end":"2026-02-24T08:08:20.085929Z","steps":["trace[1930064641] 'agreement among raft nodes before linearized reading' (duration: 959.325942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:08:20.086023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:08:19.126375Z","time spent":"959.637169ms","remote":"127.0.0.1:40458","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-02-24T08:08:22.287093Z","caller":"traceutil/trace.go:171","msg":"trace[2103483679] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"224.293179ms","start":"2026-02-24T08:08:22.062773Z","end":"2026-02-24T08:08:22.287066Z","steps":["trace[2103483679] 'process raft request' (duration: 223.670138ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:08:22.287089Z","caller":"traceutil/trace.go:171","msg":"trace[442837796] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:568; }","duration":"197.292174ms","start":"2026-02-24T08:08:22.089748Z","end":"2026-02-24T08:08:22.28704Z","steps":["trace[442837796] 'read index received' (duration: 196.747954ms)","trace[442837796] 'applied index is now lower than readState.Index' (duration: 543.32µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T08:08:22.287355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.590379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T08:08:22.287422Z","caller":"traceutil/trace.go:171","msg":"trace[658043458] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:551; }","duration":"197.695431ms","start":"2026-02-24T08:08:22.089708Z","end":"2026-02-24T08:08:22.287404Z","steps":["trace[658043458] 'agreement among raft nodes before linearized reading' (duration: 197.450607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:08:22.291887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.087611ms","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-02-24T08:08:22.291987Z","caller":"traceutil/trace.go:171","msg":"trace[272319172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:553; }","duration":"182.236214ms","start":"2026-02-24T08:08:22.109728Z","end":"2026-02-24T08:08:22.291965Z","steps":["trace[272319172] 'agreement among raft nodes before linearized reading' (duration: 181.935669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:08:22.292238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.163783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T08:08:22.292294Z","caller":"traceutil/trace.go:171","msg":"trace[456181398] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"177.254064ms","start":"2026-02-24T08:08:22.115028Z","end":"2026-02-24T08:08:22.292282Z","steps":["trace[456181398] 'agreement among raft nodes before linearized reading' (duration: 176.980529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:08:22.2925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.609721ms","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-24T08:08:22.292531Z","caller":"traceutil/trace.go:171","msg":"trace[1421589591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:553; }","duration":"182.677901ms","start":"2026-02-24T08:08:22.109845Z","end":"2026-02-24T08:08:22.292523Z","steps":["trace[1421589591] 'agreement among raft nodes before linearized reading' (duration: 182.59863ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:08:27.035656Z","caller":"traceutil/trace.go:171","msg":"trace[812868831] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"103.813549ms","start":"2026-02-24T08:08:26.9318Z","end":"2026-02-24T08:08:27.035614Z","steps":["trace[812868831] 'process raft request' (duration: 14.23842ms)","trace[812868831] 'compare' (duration: 89.436096ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:08:27.180538Z","caller":"traceutil/trace.go:171","msg":"trace[505990875] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"100.693216ms","start":"2026-02-24T08:08:27.079817Z","end":"2026-02-24T08:08:27.18051Z","steps":["trace[505990875] 'process raft request' (duration: 98.037699ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:08:27.267347Z","caller":"traceutil/trace.go:171","msg":"trace[436299560] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"185.294932ms","start":"2026-02-24T08:08:27.081975Z","end":"2026-02-24T08:08:27.26727Z","steps":["trace[436299560] 'process raft request' (duration: 184.978787ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:08:30.441614Z","caller":"traceutil/trace.go:171","msg":"trace[1694696135] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"100.535964ms","start":"2026-02-24T08:08:30.341057Z","end":"2026-02-24T08:08:30.441593Z","steps":["trace[1694696135] 'read index received' (duration: 100.28872ms)","trace[1694696135] 'applied index is now lower than readState.Index' (duration: 246.344µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:08:30.441729Z","caller":"traceutil/trace.go:171","msg":"trace[879045704] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"110.739999ms","start":"2026-02-24T08:08:30.330977Z","end":"2026-02-24T08:08:30.441717Z","steps":["trace[879045704] 'process raft request' (duration: 110.426194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:08:30.441968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.89268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/30665\" ","response":"range_response_count:1 size:1253"} {"level":"info","ts":"2026-02-24T08:08:30.442007Z","caller":"traceutil/trace.go:171","msg":"trace[3018484] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/30665; range_end:; response_count:1; response_revision:642; }","duration":"100.946621ms","start":"2026-02-24T08:08:30.341051Z","end":"2026-02-24T08:08:30.441998Z","steps":["trace[3018484] 'agreement among raft nodes before linearized reading' (duration: 100.799899ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:08:33.138625Z","caller":"traceutil/trace.go:171","msg":"trace[487410984] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"223.462678ms","start":"2026-02-24T08:08:32.915138Z","end":"2026-02-24T08:08:33.138601Z","steps":["trace[487410984] 'process raft request' (duration: 223.166243ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:09:06.57373Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740332579331314,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T08:09:06.639695Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.324927887s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T08:09:06.640134Z","caller":"traceutil/trace.go:171","msg":"trace[1887723611] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:836; }","duration":"620.620121ms","start":"2026-02-24T08:09:06.019484Z","end":"2026-02-24T08:09:06.640104Z","steps":["trace[1887723611] 'read index received' (duration: 620.338287ms)","trace[1887723611] 'applied index is now lower than readState.Index' (duration: 280.454µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:09:06.640167Z","caller":"traceutil/trace.go:171","msg":"trace[1023786520] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"1.325484705s","start":"2026-02-24T08:09:05.314656Z","end":"2026-02-24T08:09:06.64014Z","steps":["trace[1023786520] 'process raft request' (duration: 1.325286482s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:09:06.640398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.888905ms","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":"warn","ts":"2026-02-24T08:09:06.640376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:09:05.314632Z","time spent":"1.325642128s","remote":"127.0.0.1:40706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T08:09:06.640445Z","caller":"traceutil/trace.go:171","msg":"trace[426793964] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:805; }","duration":"620.982717ms","start":"2026-02-24T08:09:06.019449Z","end":"2026-02-24T08:09:06.640432Z","steps":["trace[426793964] 'agreement among raft nodes before linearized reading' (duration: 620.780214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:09:06.640485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:09:06.019431Z","time spent":"621.045458ms","remote":"127.0.0.1:40706","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":"warn","ts":"2026-02-24T08:09:06.640575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.880278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T08:09:06.640664Z","caller":"traceutil/trace.go:171","msg":"trace[967966849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"528.986599ms","start":"2026-02-24T08:09:06.111658Z","end":"2026-02-24T08:09:06.640645Z","steps":["trace[967966849] 'agreement among raft nodes before linearized reading' (duration: 528.856527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:09:06.640711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:09:06.111652Z","time spent":"529.05069ms","remote":"127.0.0.1:40458","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-02-24T08:09:51.02961Z","caller":"traceutil/trace.go:171","msg":"trace[696851639] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"121.066942ms","start":"2026-02-24T08:09:50.908519Z","end":"2026-02-24T08:09:51.029586Z","steps":["trace[696851639] 'process raft request' (duration: 120.88539ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:09:51.035136Z","caller":"traceutil/trace.go:171","msg":"trace[1362619231] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"125.656501ms","start":"2026-02-24T08:09:50.90946Z","end":"2026-02-24T08:09:51.035117Z","steps":["trace[1362619231] 'process raft request' (duration: 125.523069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:09:51.24335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.335548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T08:09:51.243446Z","caller":"traceutil/trace.go:171","msg":"trace[1798108959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:970; }","duration":"130.470979ms","start":"2026-02-24T08:09:51.112955Z","end":"2026-02-24T08:09:51.243426Z","steps":["trace[1798108959] 'range keys from in-memory index tree' (duration: 130.282387ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:09:51.279495Z","caller":"traceutil/trace.go:171","msg":"trace[510819087] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"119.726992ms","start":"2026-02-24T08:09:51.159726Z","end":"2026-02-24T08:09:51.279453Z","steps":["trace[510819087] 'process raft request' (duration: 119.52529ms)"],"step_count":1}