{"level":"warn","ts":"2026-01-28T01:05:32.238363Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-01-28T01:05:32.238729Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.74: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://162.253.55.74:2380","--initial-cluster=instance=https://162.253.55.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.74: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-01-28T01:05:32.238871Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-01-28T01:05:32.238899Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.74:2380"]}
{"level":"info","ts":"2026-01-28T01:05:32.238948Z","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-01-28T01:05:32.239916Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"]}
{"level":"info","ts":"2026-01-28T01:05:32.240055Z","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://162.253.55.74:2380"],"listen-peer-urls":["https://162.253.55.74:2380"],"advertise-client-urls":["https://162.253.55.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.74: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-01-28T01:05:32.250124Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.3756ms"}
{"level":"info","ts":"2026-01-28T01:05:32.276492Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"30aa8136fb61a722","cluster-id":"e7ea3889c4872560"}
{"level":"info","ts":"2026-01-28T01:05:32.276581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=()"}
{"level":"info","ts":"2026-01-28T01:05:32.276604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 0"}
{"level":"info","ts":"2026-01-28T01:05:32.276615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 30aa8136fb61a722 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-01-28T01:05:32.276622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 1"}
{"level":"info","ts":"2026-01-28T01:05:32.276662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"}
{"level":"warn","ts":"2026-01-28T01:05:32.294074Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-01-28T01:05:32.304701Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-01-28T01:05:32.306722Z","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-01-28T01:05:32.308583Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"30aa8136fb61a722","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-01-28T01:05:32.30941Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"30aa8136fb61a722","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-01-28T01:05:32.30973Z","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-01-28T01:05:32.309865Z","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-01-28T01:05:32.309888Z","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-01-28T01:05:32.310285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"}
{"level":"info","ts":"2026-01-28T01:05:32.310869Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e7ea3889c4872560","local-member-id":"30aa8136fb61a722","added-peer-id":"30aa8136fb61a722","added-peer-peer-urls":["https://162.253.55.74:2380"]}
{"level":"info","ts":"2026-01-28T01:05:32.330228Z","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-01-28T01:05:32.330629Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"30aa8136fb61a722","initial-advertise-peer-urls":["https://162.253.55.74:2380"],"listen-peer-urls":["https://162.253.55.74:2380"],"advertise-client-urls":["https://162.253.55.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-01-28T01:05:32.33068Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-01-28T01:05:32.330834Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.74:2380"}
{"level":"info","ts":"2026-01-28T01:05:32.330869Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.74:2380"}
{"level":"info","ts":"2026-01-28T01:05:32.377972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 is starting a new election at term 1"}
{"level":"info","ts":"2026-01-28T01:05:32.37811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became pre-candidate at term 1"}
{"level":"info","ts":"2026-01-28T01:05:32.378161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgPreVoteResp from 30aa8136fb61a722 at term 1"}
{"level":"info","ts":"2026-01-28T01:05:32.378208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became candidate at term 2"}
{"level":"info","ts":"2026-01-28T01:05:32.378225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgVoteResp from 30aa8136fb61a722 at term 2"}
{"level":"info","ts":"2026-01-28T01:05:32.37825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became leader at term 2"}
{"level":"info","ts":"2026-01-28T01:05:32.378278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 30aa8136fb61a722 elected leader 30aa8136fb61a722 at term 2"}
{"level":"info","ts":"2026-01-28T01:05:32.386202Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-01-28T01:05:32.386624Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-01-28T01:05:32.386185Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"30aa8136fb61a722","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.74:2379]}","request-path":"/0/members/30aa8136fb61a722/attributes","cluster-id":"e7ea3889c4872560","publish-timeout":"7s"}
{"level":"info","ts":"2026-01-28T01:05:32.386961Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-01-28T01:05:32.38714Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-01-28T01:05:32.387175Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-01-28T01:05:32.388694Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.74:2379"}
{"level":"info","ts":"2026-01-28T01:05:32.389932Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e7ea3889c4872560","local-member-id":"30aa8136fb61a722","cluster-version":"3.5"}
{"level":"info","ts":"2026-01-28T01:05:32.390005Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-01-28T01:05:32.390072Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-01-28T01:05:32.395087Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-01-28T01:05:57.041272Z","caller":"traceutil/trace.go:171","msg":"trace[1464683488] linearizableReadLoop","detail":"{readStateIndex:441; appliedIndex:440; }","duration":"231.354779ms","start":"2026-01-28T01:05:56.809889Z","end":"2026-01-28T01:05:57.041244Z","steps":["trace[1464683488] 'read index received'  (duration: 231.180806ms)","trace[1464683488] 'applied index is now lower than readState.Index'  (duration: 172.413µs)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T01:05:57.041427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:05:56.596217Z","time spent":"445.206267ms","remote":"127.0.0.1:45258","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""}
{"level":"warn","ts":"2026-01-28T01:05:57.041694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.795677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-28T01:05:57.041727Z","caller":"traceutil/trace.go:171","msg":"trace[482086807] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:423; }","duration":"231.914899ms","start":"2026-01-28T01:05:56.809803Z","end":"2026-01-28T01:05:57.041717Z","steps":["trace[482086807] 'agreement among raft nodes before linearized reading'  (duration: 231.836277ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:05:57.041845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.796614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-01-28T01:05:57.041861Z","caller":"traceutil/trace.go:171","msg":"trace[1444976864] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:423; }","duration":"193.872745ms","start":"2026-01-28T01:05:56.847983Z","end":"2026-01-28T01:05:57.041856Z","steps":["trace[1444976864] 'agreement among raft nodes before linearized reading'  (duration: 193.833935ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:05:57.544419Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043359886522480569,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-01-28T01:05:57.774338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.374802ms","expected-duration":"100ms","prefix":"","request":"header:<ID:12043359886522480570 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/masterleases/162.253.55.74\" mod_revision:367 > success:<request_put:<key:\"/registry/masterleases/162.253.55.74\" value_size:66 lease:2819987849667704758 >> failure:<request_range:<key:\"/registry/masterleases/162.253.55.74\" > >>","response":"size:16"}
{"level":"info","ts":"2026-01-28T01:05:57.774495Z","caller":"traceutil/trace.go:171","msg":"trace[940809553] linearizableReadLoop","detail":"{readStateIndex:443; appliedIndex:441; }","duration":"730.158093ms","start":"2026-01-28T01:05:57.044322Z","end":"2026-01-28T01:05:57.774481Z","steps":["trace[940809553] 'read index received'  (duration: 545.150891ms)","trace[940809553] 'applied index is now lower than readState.Index'  (duration: 185.006462ms)"],"step_count":2}
{"level":"info","ts":"2026-01-28T01:05:57.774558Z","caller":"traceutil/trace.go:171","msg":"trace[500059351] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"731.284552ms","start":"2026-01-28T01:05:57.043265Z","end":"2026-01-28T01:05:57.77455Z","steps":["trace[500059351] 'process raft request'  (duration: 546.199229ms)","trace[500059351] 'compare'  (duration: 184.253828ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T01:05:57.774612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:05:57.043248Z","time spent":"731.337674ms","remote":"127.0.0.1:45258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/masterleases/162.253.55.74\" mod_revision:367 > success:<request_put:<key:\"/registry/masterleases/162.253.55.74\" value_size:66 lease:2819987849667704758 >> failure:<request_range:<key:\"/registry/masterleases/162.253.55.74\" > >"}
{"level":"info","ts":"2026-01-28T01:05:57.774942Z","caller":"traceutil/trace.go:171","msg":"trace[12456827] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"727.835391ms","start":"2026-01-28T01:05:57.047099Z","end":"2026-01-28T01:05:57.774934Z","steps":["trace[12456827] 'process raft request'  (duration: 727.333242ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:05:57.774986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:05:57.047079Z","time spent":"727.886782ms","remote":"127.0.0.1:45466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:419 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"warn","ts":"2026-01-28T01:05:57.775106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.785604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-28T01:05:57.775133Z","caller":"traceutil/trace.go:171","msg":"trace[1068657913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:425; }","duration":"730.810415ms","start":"2026-01-28T01:05:57.044315Z","end":"2026-01-28T01:05:57.775125Z","steps":["trace[1068657913] 'agreement among raft nodes before linearized reading'  (duration: 730.742343ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:05:57.775153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:05:57.044242Z","time spent":"730.907296ms","remote":"127.0.0.1:45240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-28T01:05:57.77526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.45174ms","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-01-28T01:05:57.775284Z","caller":"traceutil/trace.go:171","msg":"trace[1375814156] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:425; }","duration":"686.544291ms","start":"2026-01-28T01:05:57.088734Z","end":"2026-01-28T01:05:57.775279Z","steps":["trace[1375814156] 'agreement among raft nodes before linearized reading'  (duration: 686.504281ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:05:57.775305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:05:57.088709Z","time spent":"686.591843ms","remote":"127.0.0.1:45466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-01-28T01:05:57.775468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.457183ms","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-01-28T01:05:57.775494Z","caller":"traceutil/trace.go:171","msg":"trace[1121297213] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:425; }","duration":"427.529594ms","start":"2026-01-28T01:05:57.347959Z","end":"2026-01-28T01:05:57.775489Z","steps":["trace[1121297213] 'agreement among raft nodes before linearized reading'  (duration: 427.486903ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:05:57.775515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:05:57.347935Z","time spent":"427.577076ms","remote":"127.0.0.1:45466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-01-28T01:05:57.781981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.163295ms","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-01-28T01:05:57.782139Z","caller":"traceutil/trace.go:171","msg":"trace[1832017501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:425; }","duration":"691.317197ms","start":"2026-01-28T01:05:57.090754Z","end":"2026-01-28T01:05:57.782072Z","steps":["trace[1832017501] 'agreement among raft nodes before linearized reading'  (duration: 684.835031ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:05:57.782201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:05:57.090744Z","time spent":"691.44781ms","remote":"127.0.0.1:45466","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-01-28T01:06:06.722207Z","caller":"traceutil/trace.go:171","msg":"trace[2047141719] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"136.338865ms","start":"2026-01-28T01:06:06.58583Z","end":"2026-01-28T01:06:06.722169Z","steps":["trace[2047141719] 'process raft request'  (duration: 136.04638ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T01:06:06.722787Z","caller":"traceutil/trace.go:171","msg":"trace[53655287] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:533; }","duration":"126.518107ms","start":"2026-01-28T01:06:06.59626Z","end":"2026-01-28T01:06:06.722778Z","steps":["trace[53655287] 'read index received'  (duration: 126.514047ms)","trace[53655287] 'applied index is now lower than readState.Index'  (duration: 3.36µs)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T01:06:07.308333Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043359886522480733,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-01-28T01:06:07.715863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.119579926s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.74\" ","response":"range_response_count:1 size:133"}
{"level":"info","ts":"2026-01-28T01:06:07.715939Z","caller":"traceutil/trace.go:171","msg":"trace[102444075] range","detail":"{range_begin:/registry/masterleases/162.253.55.74; range_end:; response_count:1; response_revision:514; }","duration":"1.119688317s","start":"2026-01-28T01:06:06.596232Z","end":"2026-01-28T01:06:07.71592Z","steps":["trace[102444075] 'agreement among raft nodes before linearized reading'  (duration: 126.595568ms)","trace[102444075] 'range keys from in-memory index tree'  (duration: 992.975107ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T01:06:07.715977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:06:06.596218Z","time spent":"1.119751038s","remote":"127.0.0.1:45258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.74\" "}
{"level":"warn","ts":"2026-01-28T01:06:07.808672Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043359886522480733,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-01-28T01:06:08.18932Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.234948922s","expected-duration":"1s"}
{"level":"info","ts":"2026-01-28T01:06:08.189939Z","caller":"traceutil/trace.go:171","msg":"trace[1772489374] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"1.458607789s","start":"2026-01-28T01:06:06.731272Z","end":"2026-01-28T01:06:08.189879Z","steps":["trace[1772489374] 'process raft request'  (duration: 1.458471797s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:06:08.190069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:06:06.731253Z","time spent":"1.458733872s","remote":"127.0.0.1:45278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10353,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" mod_revision:514 > success:<request_put:<key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" value_size:10250 >> failure:<request_range:<key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" > >"}
{"level":"warn","ts":"2026-01-28T01:06:08.808233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000248607s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"}
{"level":"info","ts":"2026-01-28T01:06:08.808291Z","caller":"traceutil/trace.go:171","msg":"trace[1583068695] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000368889s","start":"2026-01-28T01:06:06.80791Z","end":"2026-01-28T01:06:08.808279Z","steps":["trace[1583068695] 'agreement among raft nodes before linearized reading'  (duration: 2.000297868s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:06:08.808315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:06:06.807896Z","time spent":"2.00041465s","remote":"127.0.0.1:45224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "}
2026/01/28 01:06:08 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing"
{"level":"info","ts":"2026-01-28T01:06:08.843177Z","caller":"traceutil/trace.go:171","msg":"trace[1964169771] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:533; }","duration":"2.035183033s","start":"2026-01-28T01:06:06.807975Z","end":"2026-01-28T01:06:08.843158Z","steps":["trace[1964169771] 'read index received'  (duration: 1.381577894s)","trace[1964169771] 'applied index is now lower than readState.Index'  (duration: 653.604469ms)"],"step_count":2}
{"level":"info","ts":"2026-01-28T01:06:08.843429Z","caller":"traceutil/trace.go:171","msg":"trace[1019950901] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"2.05712591s","start":"2026-01-28T01:06:06.786296Z","end":"2026-01-28T01:06:08.843422Z","steps":["trace[1019950901] 'process raft request'  (duration: 2.056688403s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:06:08.84351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:06:06.78627Z","time spent":"2.057188861s","remote":"127.0.0.1:45278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" mod_revision:0 > success:<request_put:<key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" value_size:12154 >> failure:<>"}
{"level":"warn","ts":"2026-01-28T01:06:08.843645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:06:07.717475Z","time spent":"1.126168759s","remote":"127.0.0.1:45258","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""}
{"level":"warn","ts":"2026-01-28T01:06:08.882867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.511211ms","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-01-28T01:06:08.88296Z","caller":"traceutil/trace.go:171","msg":"trace[1281013244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:517; }","duration":"994.658133ms","start":"2026-01-28T01:06:07.888283Z","end":"2026-01-28T01:06:08.882941Z","steps":["trace[1281013244] 'agreement among raft nodes before linearized reading'  (duration: 994.402689ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:06:08.883042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:06:07.888272Z","time spent":"994.761466ms","remote":"127.0.0.1:45466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"info","ts":"2026-01-28T01:06:08.883467Z","caller":"traceutil/trace.go:171","msg":"trace[594661853] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"686.516179ms","start":"2026-01-28T01:06:08.19694Z","end":"2026-01-28T01:06:08.883456Z","steps":["trace[594661853] 'process raft request'  (duration: 685.450051ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:06:08.883662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:06:08.196924Z","time spent":"686.56964ms","remote":"127.0.0.1:45278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10362,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" mod_revision:515 > success:<request_put:<key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" value_size:10259 >> failure:<request_range:<key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" > >"}
{"level":"warn","ts":"2026-01-28T01:06:08.884001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.551539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"}
{"level":"info","ts":"2026-01-28T01:06:08.884031Z","caller":"traceutil/trace.go:171","msg":"trace[2096693630] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:517; }","duration":"102.60626ms","start":"2026-01-28T01:06:08.781417Z","end":"2026-01-28T01:06:08.884023Z","steps":["trace[2096693630] 'agreement among raft nodes before linearized reading'  (duration: 102.540759ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:06:08.884291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.708233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"}
{"level":"info","ts":"2026-01-28T01:06:08.884338Z","caller":"traceutil/trace.go:171","msg":"trace[2042134574] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:517; }","duration":"302.803154ms","start":"2026-01-28T01:06:08.581527Z","end":"2026-01-28T01:06:08.88433Z","steps":["trace[2042134574] 'agreement among raft nodes before linearized reading'  (duration: 302.686522ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:06:08.88436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:06:08.581509Z","time spent":"302.844456ms","remote":"127.0.0.1:45278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24267,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "}
{"level":"warn","ts":"2026-01-28T01:06:08.884639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.809363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"}
{"level":"info","ts":"2026-01-28T01:06:08.884687Z","caller":"traceutil/trace.go:171","msg":"trace[2101074645] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:517; }","duration":"503.892765ms","start":"2026-01-28T01:06:08.380788Z","end":"2026-01-28T01:06:08.884681Z","steps":["trace[2101074645] 'agreement among raft nodes before linearized reading'  (duration: 503.774403ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:06:08.884786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:06:08.380769Z","time spent":"503.933455ms","remote":"127.0.0.1:45278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4356,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "}
{"level":"warn","ts":"2026-01-28T01:06:08.885088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.065159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"}
{"level":"info","ts":"2026-01-28T01:06:08.885135Z","caller":"traceutil/trace.go:171","msg":"trace[646125747] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:517; }","duration":"704.137211ms","start":"2026-01-28T01:06:08.180991Z","end":"2026-01-28T01:06:08.885129Z","steps":["trace[646125747] 'agreement among raft nodes before linearized reading'  (duration: 703.994728ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:06:08.885234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:06:08.180979Z","time spent":"704.174791ms","remote":"127.0.0.1:45278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9343,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "}
{"level":"warn","ts":"2026-01-28T01:06:08.885407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.897999647s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-28T01:06:08.885463Z","caller":"traceutil/trace.go:171","msg":"trace[1588984839] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:517; }","duration":"1.898094908s","start":"2026-01-28T01:06:06.987358Z","end":"2026-01-28T01:06:08.885453Z","steps":["trace[1588984839] 'agreement among raft nodes before linearized reading'  (duration: 1.89528863s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:06:08.885621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:06:06.987337Z","time spent":"1.898271772s","remote":"127.0.0.1:45278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "}
{"level":"warn","ts":"2026-01-28T01:06:08.886437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.205854ms","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-01-28T01:06:08.886461Z","caller":"traceutil/trace.go:171","msg":"trace[1953179492] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:517; }","duration":"998.265935ms","start":"2026-01-28T01:06:07.888188Z","end":"2026-01-28T01:06:08.886454Z","steps":["trace[1953179492] 'agreement among raft nodes before linearized reading'  (duration: 998.195114ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:06:08.886691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:06:07.888168Z","time spent":"998.479629ms","remote":"127.0.0.1:45466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-01-28T01:06:08.886952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001216506s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-01-28T01:06:08.887058Z","caller":"traceutil/trace.go:171","msg":"trace[110033980] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:517; }","duration":"1.001322927s","start":"2026-01-28T01:06:07.885727Z","end":"2026-01-28T01:06:08.88705Z","steps":["trace[110033980] 'agreement among raft nodes before linearized reading'  (duration: 1.001152945s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:06:08.887142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:06:07.885692Z","time spent":"1.00144155s","remote":"127.0.0.1:45466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-01-28T01:06:08.887493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024278791s","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-01-28T01:06:08.887621Z","caller":"traceutil/trace.go:171","msg":"trace[271947802] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:517; }","duration":"1.024435594s","start":"2026-01-28T01:06:07.863179Z","end":"2026-01-28T01:06:08.887614Z","steps":["trace[271947802] 'agreement among raft nodes before linearized reading'  (duration: 1.024162859s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:06:08.887785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:06:07.863164Z","time spent":"1.024612537s","remote":"127.0.0.1:45466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-01-28T01:06:08.888113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.620300049s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"}
{"level":"warn","ts":"2026-01-28T01:06:08.888339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.707490412s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-28T01:06:08.88837Z","caller":"traceutil/trace.go:171","msg":"trace[1455848573] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:517; }","duration":"1.707560224s","start":"2026-01-28T01:06:07.1808Z","end":"2026-01-28T01:06:08.88836Z","steps":["trace[1455848573] 'agreement among raft nodes before linearized reading'  (duration: 1.707503433s)"],"step_count":1}
{"level":"info","ts":"2026-01-28T01:06:08.888143Z","caller":"traceutil/trace.go:171","msg":"trace[1284831770] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:517; }","duration":"1.62036733s","start":"2026-01-28T01:06:07.26777Z","end":"2026-01-28T01:06:08.888137Z","steps":["trace[1284831770] 'agreement among raft nodes before linearized reading'  (duration: 1.620288159s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:06:08.88855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:06:07.180782Z","time spent":"1.707757647s","remote":"127.0.0.1:45278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "}
{"level":"warn","ts":"2026-01-28T01:06:08.888691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:06:07.267748Z","time spent":"1.62093285s","remote":"127.0.0.1:45466","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-01-28T01:06:08.889208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.108685477s","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-01-28T01:06:08.889239Z","caller":"traceutil/trace.go:171","msg":"trace[1874230577] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:517; }","duration":"1.108769918s","start":"2026-01-28T01:06:07.780463Z","end":"2026-01-28T01:06:08.889233Z","steps":["trace[1874230577] 'agreement among raft nodes before linearized reading'  (duration: 1.108671956s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:06:08.889425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:06:07.780449Z","time spent":"1.10890631s","remote":"127.0.0.1:45278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5200,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "}
{"level":"warn","ts":"2026-01-28T01:06:08.895791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.314151395s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"}
{"level":"info","ts":"2026-01-28T01:06:08.895815Z","caller":"traceutil/trace.go:171","msg":"trace[755568853] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:517; }","duration":"1.314202516s","start":"2026-01-28T01:06:07.581606Z","end":"2026-01-28T01:06:08.895808Z","steps":["trace[755568853] 'agreement among raft nodes before linearized reading'  (duration: 1.314143165s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:06:08.895831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:06:07.581593Z","time spent":"1.314234206s","remote":"127.0.0.1:45278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4203,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "}
{"level":"warn","ts":"2026-01-28T01:06:08.895907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.483701089s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-28T01:06:08.895926Z","caller":"traceutil/trace.go:171","msg":"trace[1768204730] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:517; }","duration":"1.48374388s","start":"2026-01-28T01:06:07.412178Z","end":"2026-01-28T01:06:08.895921Z","steps":["trace[1768204730] 'agreement among raft nodes before linearized reading'  (duration: 1.483712609s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:06:08.895956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:06:07.412163Z","time spent":"1.48377779s","remote":"127.0.0.1:45326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "}
{"level":"warn","ts":"2026-01-28T01:06:08.896069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.515757738s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"}
{"level":"info","ts":"2026-01-28T01:06:08.896087Z","caller":"traceutil/trace.go:171","msg":"trace[1273160779] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:517; }","duration":"1.515804368s","start":"2026-01-28T01:06:07.380279Z","end":"2026-01-28T01:06:08.896083Z","steps":["trace[1273160779] 'agreement among raft nodes before linearized reading'  (duration: 1.515752218s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:06:08.896101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:06:07.380262Z","time spent":"1.51583511s","remote":"127.0.0.1:45278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5609,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "}
{"level":"warn","ts":"2026-01-28T01:06:08.885824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.1333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"}
{"level":"info","ts":"2026-01-28T01:06:08.900885Z","caller":"traceutil/trace.go:171","msg":"trace[302701098] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:517; }","duration":"920.212908ms","start":"2026-01-28T01:06:07.980665Z","end":"2026-01-28T01:06:08.900878Z","steps":["trace[302701098] 'agreement among raft nodes before linearized reading'  (duration: 905.10115ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:06:08.900919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:06:07.980655Z","time spent":"920.257498ms","remote":"127.0.0.1:45278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4106,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "}
{"level":"info","ts":"2026-01-28T01:06:15.714501Z","caller":"traceutil/trace.go:171","msg":"trace[2080350011] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"282.283482ms","start":"2026-01-28T01:06:15.4322Z","end":"2026-01-28T01:06:15.714484Z","steps":["trace[2080350011] 'process raft request'  (duration: 282.1501ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T01:06:15.860784Z","caller":"traceutil/trace.go:171","msg":"trace[1747659162] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"171.627737ms","start":"2026-01-28T01:06:15.68914Z","end":"2026-01-28T01:06:15.860767Z","steps":["trace[1747659162] 'process raft request'  (duration: 155.885876ms)","trace[1747659162] 'compare'  (duration: 15.342655ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T01:06:18.057049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.460167622s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.74\" ","response":"range_response_count:1 size:133"}
{"level":"info","ts":"2026-01-28T01:06:18.057127Z","caller":"traceutil/trace.go:171","msg":"trace[1950981543] range","detail":"{range_begin:/registry/masterleases/162.253.55.74; range_end:; response_count:1; response_revision:577; }","duration":"1.460287844s","start":"2026-01-28T01:06:16.596823Z","end":"2026-01-28T01:06:18.057111Z","steps":["trace[1950981543] 'range keys from in-memory index tree'  (duration: 1.46004022s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:06:18.057153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:06:16.596807Z","time spent":"1.460340065s","remote":"127.0.0.1:45258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.74\" "}
{"level":"warn","ts":"2026-01-28T01:06:18.057294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.248598646s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-28T01:06:18.057308Z","caller":"traceutil/trace.go:171","msg":"trace[651168751] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"1.248664507s","start":"2026-01-28T01:06:16.808639Z","end":"2026-01-28T01:06:18.057303Z","steps":["trace[651168751] 'range keys from in-memory index tree'  (duration: 1.248468894s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:06:18.057321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:06:16.808608Z","time spent":"1.248709778s","remote":"127.0.0.1:45224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-28T01:06:18.057638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.076546733s","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-01-28T01:06:18.057656Z","caller":"traceutil/trace.go:171","msg":"trace[260883913] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:577; }","duration":"1.076595623s","start":"2026-01-28T01:06:16.981056Z","end":"2026-01-28T01:06:18.057652Z","steps":["trace[260883913] 'range keys from in-memory index tree'  (duration: 1.076476351s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:06:18.057668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:06:16.981035Z","time spent":"1.076629794s","remote":"127.0.0.1:45466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-01-28T01:06:18.057766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040155332s","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-01-28T01:06:18.05778Z","caller":"traceutil/trace.go:171","msg":"trace[2048603481] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:577; }","duration":"1.040204632s","start":"2026-01-28T01:06:17.017572Z","end":"2026-01-28T01:06:18.057777Z","steps":["trace[2048603481] 'range keys from in-memory index tree'  (duration: 1.040031039s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:06:18.057795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:06:17.01755Z","time spent":"1.040242723s","remote":"127.0.0.1:45466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-01-28T01:06:18.057871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005856007s","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-01-28T01:06:18.057884Z","caller":"traceutil/trace.go:171","msg":"trace[714525612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:577; }","duration":"1.005870248s","start":"2026-01-28T01:06:17.05201Z","end":"2026-01-28T01:06:18.05788Z","steps":["trace[714525612] 'range keys from in-memory index tree'  (duration: 1.005771446s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:06:18.057916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:06:17.05195Z","time spent":"1.005942489s","remote":"127.0.0.1:45466","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-01-28T01:06:18.057993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.003849574s","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-01-28T01:06:18.058008Z","caller":"traceutil/trace.go:171","msg":"trace[1669191094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:577; }","duration":"1.003876935s","start":"2026-01-28T01:06:17.054124Z","end":"2026-01-28T01:06:18.058001Z","steps":["trace[1669191094] 'range keys from in-memory index tree'  (duration: 1.003804294s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:06:18.05802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:06:17.054118Z","time spent":"1.003897906s","remote":"127.0.0.1:45466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-01-28T01:06:18.058156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.838966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"}
{"level":"info","ts":"2026-01-28T01:06:18.058173Z","caller":"traceutil/trace.go:171","msg":"trace[99916793] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:577; }","duration":"864.895526ms","start":"2026-01-28T01:06:17.193272Z","end":"2026-01-28T01:06:18.058168Z","steps":["trace[99916793] 'range keys from in-memory index tree'  (duration: 864.763455ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:06:18.05819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:06:17.19325Z","time spent":"864.935408ms","remote":"127.0.0.1:45466","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":"info","ts":"2026-01-28T01:06:18.333428Z","caller":"traceutil/trace.go:171","msg":"trace[467374716] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"261.845515ms","start":"2026-01-28T01:06:18.071551Z","end":"2026-01-28T01:06:18.333397Z","steps":["trace[467374716] 'process raft request'  (duration: 261.114733ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T01:06:18.33407Z","caller":"traceutil/trace.go:171","msg":"trace[1060266670] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"262.400454ms","start":"2026-01-28T01:06:18.071655Z","end":"2026-01-28T01:06:18.334055Z","steps":["trace[1060266670] 'process raft request'  (duration: 261.338776ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T01:06:18.33466Z","caller":"traceutil/trace.go:171","msg":"trace[376381975] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:599; }","duration":"257.775799ms","start":"2026-01-28T01:06:18.076702Z","end":"2026-01-28T01:06:18.334478Z","steps":["trace[376381975] 'read index received'  (duration: 21.482993ms)","trace[376381975] 'applied index is now lower than readState.Index'  (duration: 236.291456ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T01:06:18.334939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.207606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-28T01:06:18.334967Z","caller":"traceutil/trace.go:171","msg":"trace[416944792] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"263.148036ms","start":"2026-01-28T01:06:18.071787Z","end":"2026-01-28T01:06:18.334935Z","steps":["trace[416944792] 'process raft request'  (duration: 262.048088ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T01:06:18.335408Z","caller":"traceutil/trace.go:171","msg":"trace[499115708] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"261.176394ms","start":"2026-01-28T01:06:18.074224Z","end":"2026-01-28T01:06:18.3354Z","steps":["trace[499115708] 'process raft request'  (duration: 260.124787ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T01:06:18.335518Z","caller":"traceutil/trace.go:171","msg":"trace[48653277] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"261.249305ms","start":"2026-01-28T01:06:18.074262Z","end":"2026-01-28T01:06:18.335512Z","steps":["trace[48653277] 'process raft request'  (duration: 260.169157ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T01:06:18.335954Z","caller":"traceutil/trace.go:171","msg":"trace[416401802] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:582; }","duration":"258.340268ms","start":"2026-01-28T01:06:18.076671Z","end":"2026-01-28T01:06:18.335011Z","steps":["trace[416401802] 'agreement among raft nodes before linearized reading'  (duration: 258.092994ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T01:06:18.336561Z","caller":"traceutil/trace.go:171","msg":"trace[971954409] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"237.73201ms","start":"2026-01-28T01:06:18.098818Z","end":"2026-01-28T01:06:18.33655Z","steps":["trace[971954409] 'process raft request'  (duration: 237.660369ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:07:13.203668Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043359886522481484,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-01-28T01:07:13.704744Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043359886522481484,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-01-28T01:07:13.992001Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.292020827s","expected-duration":"1s"}
{"level":"info","ts":"2026-01-28T01:07:13.992782Z","caller":"traceutil/trace.go:171","msg":"trace[505693700] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"1.292831058s","start":"2026-01-28T01:07:12.699902Z","end":"2026-01-28T01:07:13.992733Z","steps":["trace[505693700] 'process raft request'  (duration: 1.292443453s)"],"step_count":1}
{"level":"info","ts":"2026-01-28T01:07:13.993438Z","caller":"traceutil/trace.go:171","msg":"trace[533856644] linearizableReadLoop","detail":"{readStateIndex:878; appliedIndex:876; }","duration":"1.290476264s","start":"2026-01-28T01:07:12.702945Z","end":"2026-01-28T01:07:13.993421Z","steps":["trace[533856644] 'read index received'  (duration: 1.289746164s)","trace[533856644] 'applied index is now lower than readState.Index'  (duration: 726.64µs)"],"step_count":2}
{"level":"info","ts":"2026-01-28T01:07:13.993673Z","caller":"traceutil/trace.go:171","msg":"trace[1658338341] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"1.291784633s","start":"2026-01-28T01:07:12.701874Z","end":"2026-01-28T01:07:13.993659Z","steps":["trace[1658338341] 'process raft request'  (duration: 1.291475829s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:07:13.993753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:07:12.701868Z","time spent":"1.291836534s","remote":"127.0.0.1:45466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:839 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"warn","ts":"2026-01-28T01:07:13.99293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:07:12.69989Z","time spent":"1.29294735s","remote":"127.0.0.1:45466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:837 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"warn","ts":"2026-01-28T01:07:13.995176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.29223321s","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-01-28T01:07:13.995223Z","caller":"traceutil/trace.go:171","msg":"trace[932846719] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:843; }","duration":"1.29233332s","start":"2026-01-28T01:07:12.702876Z","end":"2026-01-28T01:07:13.995209Z","steps":["trace[932846719] 'agreement among raft nodes before linearized reading'  (duration: 1.292174718s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:07:13.995262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:07:12.702853Z","time spent":"1.292397782s","remote":"127.0.0.1:45466","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-01-28T01:07:13.995504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.185105297s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-28T01:07:13.995537Z","caller":"traceutil/trace.go:171","msg":"trace[871781252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:843; }","duration":"1.185231829s","start":"2026-01-28T01:07:12.810295Z","end":"2026-01-28T01:07:13.995527Z","steps":["trace[871781252] 'agreement among raft nodes before linearized reading'  (duration: 1.185047416s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:07:13.995565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:07:12.810267Z","time spent":"1.185291079s","remote":"127.0.0.1:45224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-28T01:07:15.129277Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043359886522481493,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-01-28T01:07:15.326766Z","caller":"traceutil/trace.go:171","msg":"trace[1957533432] linearizableReadLoop","detail":"{readStateIndex:880; appliedIndex:879; }","duration":"698.117778ms","start":"2026-01-28T01:07:14.628618Z","end":"2026-01-28T01:07:15.326736Z","steps":["trace[1957533432] 'read index received'  (duration: 697.890345ms)","trace[1957533432] 'applied index is now lower than readState.Index'  (duration: 226.313µs)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T01:07:15.327513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.859909ms","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-01-28T01:07:15.327566Z","caller":"traceutil/trace.go:171","msg":"trace[430660547] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:845; }","duration":"698.95957ms","start":"2026-01-28T01:07:14.628593Z","end":"2026-01-28T01:07:15.327553Z","steps":["trace[430660547] 'agreement among raft nodes before linearized reading'  (duration: 698.25761ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T01:07:15.327543Z","caller":"traceutil/trace.go:171","msg":"trace[1185330231] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"719.684544ms","start":"2026-01-28T01:07:14.607829Z","end":"2026-01-28T01:07:15.327513Z","steps":["trace[1185330231] 'process raft request'  (duration: 718.757261ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:07:15.328106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:07:14.607816Z","time spent":"720.191161ms","remote":"127.0.0.1:45466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:840 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"warn","ts":"2026-01-28T01:07:15.329984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:07:14.628583Z","time spent":"699.077402ms","remote":"127.0.0.1:45466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-01-28T01:07:17.422769Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043359886522481507,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-01-28T01:07:17.771271Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.168015904s","expected-duration":"1s"}
{"level":"warn","ts":"2026-01-28T01:07:17.771868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:07:16.603034Z","time spent":"1.168829715s","remote":"127.0.0.1:45258","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""}
{"level":"info","ts":"2026-01-28T01:07:17.772546Z","caller":"traceutil/trace.go:171","msg":"trace[1045545598] linearizableReadLoop","detail":"{readStateIndex:886; appliedIndex:885; }","duration":"965.302874ms","start":"2026-01-28T01:07:16.807221Z","end":"2026-01-28T01:07:17.772524Z","steps":["trace[1045545598] 'read index received'  (duration: 964.893018ms)","trace[1045545598] 'applied index is now lower than readState.Index'  (duration: 409.036µs)"],"step_count":2}
{"level":"info","ts":"2026-01-28T01:07:17.772991Z","caller":"traceutil/trace.go:171","msg":"trace[184333451] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"840.237158ms","start":"2026-01-28T01:07:16.932741Z","end":"2026-01-28T01:07:17.772978Z","steps":["trace[184333451] 'process raft request'  (duration: 839.624619ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:07:17.773231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:07:16.93272Z","time spent":"840.31229ms","remote":"127.0.0.1:45466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" mod_revision:828 > success:<request_put:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" value_size:600 >> failure:<request_range:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" > >"}
{"level":"warn","ts":"2026-01-28T01:07:17.773673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.431869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-28T01:07:17.773725Z","caller":"traceutil/trace.go:171","msg":"trace[162221087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:850; }","duration":"966.52618ms","start":"2026-01-28T01:07:16.807185Z","end":"2026-01-28T01:07:17.773711Z","steps":["trace[162221087] 'agreement among raft nodes before linearized reading'  (duration: 966.437569ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:07:17.774353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:07:16.807167Z","time spent":"967.17134ms","remote":"127.0.0.1:45224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-28T01:07:17.774733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.50275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4197"}
{"level":"info","ts":"2026-01-28T01:07:17.774774Z","caller":"traceutil/trace.go:171","msg":"trace[1788451153] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:850; }","duration":"720.579451ms","start":"2026-01-28T01:07:17.054184Z","end":"2026-01-28T01:07:17.774764Z","steps":["trace[1788451153] 'agreement among raft nodes before linearized reading'  (duration: 720.443019ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:07:17.774981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:07:17.054167Z","time spent":"720.733193ms","remote":"127.0.0.1:45636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4220,"request content":"key:\"/registry/deployments/kube-system/coredns\" "}
{"level":"warn","ts":"2026-01-28T01:07:17.775576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.506609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-01-28T01:07:17.775622Z","caller":"traceutil/trace.go:171","msg":"trace[1507544937] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:850; }","duration":"179.557309ms","start":"2026-01-28T01:07:17.596054Z","end":"2026-01-28T01:07:17.775611Z","steps":["trace[1507544937] 'agreement among raft nodes before linearized reading'  (duration: 179.459878ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:07:17.776177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.67705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-01-28T01:07:17.776219Z","caller":"traceutil/trace.go:171","msg":"trace[597544401] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:850; }","duration":"350.737531ms","start":"2026-01-28T01:07:17.425471Z","end":"2026-01-28T01:07:17.776209Z","steps":["trace[597544401] 'agreement among raft nodes before linearized reading'  (duration: 350.626859ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:07:17.776315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:07:17.425464Z","time spent":"350.836532ms","remote":"127.0.0.1:45466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-01-28T01:07:17.776602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.647878ms","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-01-28T01:07:17.778363Z","caller":"traceutil/trace.go:171","msg":"trace[1354482952] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:850; }","duration":"359.486834ms","start":"2026-01-28T01:07:17.41886Z","end":"2026-01-28T01:07:17.778347Z","steps":["trace[1354482952] 'agreement among raft nodes before linearized reading'  (duration: 357.286413ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T01:07:17.778497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:07:17.418836Z","time spent":"359.650517ms","remote":"127.0.0.1:45466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"info","ts":"2026-01-28T01:07:50.131577Z","caller":"traceutil/trace.go:171","msg":"trace[556844218] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"113.801392ms","start":"2026-01-28T01:07:50.017759Z","end":"2026-01-28T01:07:50.13156Z","steps":["trace[556844218] 'process raft request'  (duration: 113.681041ms)"],"step_count":1}