{"level":"warn","ts":"2026-02-14T04:55:23.497748Z","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-14T04:55:23.497936Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.212: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.212:2380","--initial-cluster=instance=https://162.253.55.212:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.212:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.212: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-14T04:55:23.498051Z","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-14T04:55:23.498096Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.212:2380"]} {"level":"info","ts":"2026-02-14T04:55:23.49813Z","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-14T04:55:23.498811Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"]} {"level":"info","ts":"2026-02-14T04:55:23.498905Z","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.212:2380"],"listen-peer-urls":["https://162.253.55.212:2380"],"advertise-client-urls":["https://162.253.55.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.212: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-14T04:55:23.505515Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.346449ms"} {"level":"info","ts":"2026-02-14T04:55:23.525376Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d078b1e39c21939c","cluster-id":"cdc9ba8de57e3589"} {"level":"info","ts":"2026-02-14T04:55:23.525465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=()"} {"level":"info","ts":"2026-02-14T04:55:23.525516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became follower at term 0"} {"level":"info","ts":"2026-02-14T04:55:23.525546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d078b1e39c21939c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-14T04:55:23.525565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became follower at term 1"} {"level":"info","ts":"2026-02-14T04:55:23.525632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=(15021952148229428124)"} {"level":"warn","ts":"2026-02-14T04:55:23.540576Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-14T04:55:23.549613Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-14T04:55:23.561376Z","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-14T04:55:23.570323Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d078b1e39c21939c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-14T04:55:23.570692Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d078b1e39c21939c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-14T04:55:23.570807Z","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-14T04:55:23.570873Z","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-14T04:55:23.573054Z","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-14T04:55:23.571733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=(15021952148229428124)"} {"level":"info","ts":"2026-02-14T04:55:23.573321Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cdc9ba8de57e3589","local-member-id":"d078b1e39c21939c","added-peer-id":"d078b1e39c21939c","added-peer-peer-urls":["https://162.253.55.212:2380"]} {"level":"info","ts":"2026-02-14T04:55:23.586219Z","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-14T04:55:23.586347Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.212:2380"} {"level":"info","ts":"2026-02-14T04:55:23.586883Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.212:2380"} {"level":"info","ts":"2026-02-14T04:55:23.587359Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d078b1e39c21939c","initial-advertise-peer-urls":["https://162.253.55.212:2380"],"listen-peer-urls":["https://162.253.55.212:2380"],"advertise-client-urls":["https://162.253.55.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-14T04:55:23.587401Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-14T04:55:24.526805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c is starting a new election at term 1"} {"level":"info","ts":"2026-02-14T04:55:24.52689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became pre-candidate at term 1"} {"level":"info","ts":"2026-02-14T04:55:24.526937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c received MsgPreVoteResp from d078b1e39c21939c at term 1"} {"level":"info","ts":"2026-02-14T04:55:24.52697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became candidate at term 2"} {"level":"info","ts":"2026-02-14T04:55:24.526989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c received MsgVoteResp from d078b1e39c21939c at term 2"} {"level":"info","ts":"2026-02-14T04:55:24.527024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became leader at term 2"} {"level":"info","ts":"2026-02-14T04:55:24.527048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d078b1e39c21939c elected leader d078b1e39c21939c at term 2"} {"level":"info","ts":"2026-02-14T04:55:24.528009Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-14T04:55:24.529142Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdc9ba8de57e3589","local-member-id":"d078b1e39c21939c","cluster-version":"3.5"} {"level":"info","ts":"2026-02-14T04:55:24.529224Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-14T04:55:24.529282Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-14T04:55:24.529318Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d078b1e39c21939c","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.212:2379]}","request-path":"/0/members/d078b1e39c21939c/attributes","cluster-id":"cdc9ba8de57e3589","publish-timeout":"7s"} {"level":"info","ts":"2026-02-14T04:55:24.529359Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-14T04:55:24.529677Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-14T04:55:24.531054Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.212:2379"} {"level":"info","ts":"2026-02-14T04:55:24.532345Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-14T04:55:24.533296Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-14T04:55:24.533347Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-02-14T04:55:26.633088Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548332465980791,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-14T04:55:26.656748Z","caller":"traceutil/trace.go:171","msg":"trace[1640438819] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"525.291934ms","start":"2026-02-14T04:55:26.131434Z","end":"2026-02-14T04:55:26.656726Z","steps":["trace[1640438819] 'process raft request' (duration: 525.029508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:26.659312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:26.131423Z","time spent":"525.406887ms","remote":"127.0.0.1:55790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-14T04:55:26.963321Z","caller":"traceutil/trace.go:171","msg":"trace[93014406] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"828.596241ms","start":"2026-02-14T04:55:26.13463Z","end":"2026-02-14T04:55:26.963226Z","steps":["trace[93014406] 'process raft request' (duration: 828.274504ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:55:26.964457Z","caller":"traceutil/trace.go:171","msg":"trace[230383909] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:4; }","duration":"832.058187ms","start":"2026-02-14T04:55:26.132273Z","end":"2026-02-14T04:55:26.964331Z","steps":["trace[230383909] 'read index received' (duration: 524.099078ms)","trace[230383909] 'applied index is now lower than readState.Index' (duration: 307.958039ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:55:26.964425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:26.134618Z","time spent":"828.883478ms","remote":"127.0.0.1:55292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:55:26.969434Z","caller":"traceutil/trace.go:171","msg":"trace[1673427179] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"834.731875ms","start":"2026-02-14T04:55:26.134676Z","end":"2026-02-14T04:55:26.969408Z","steps":["trace[1673427179] 'process raft request' (duration: 828.475439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:26.969923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:26.134672Z","time spent":"834.94595ms","remote":"127.0.0.1:55790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-14T04:55:26.970467Z","caller":"traceutil/trace.go:171","msg":"trace[1449756304] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"835.692426ms","start":"2026-02-14T04:55:26.134719Z","end":"2026-02-14T04:55:26.970411Z","steps":["trace[1449756304] 'process raft request' (duration: 828.688783ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:26.970507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:26.134716Z","time spent":"835.771529ms","remote":"127.0.0.1:55690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-14T04:55:26.97064Z","caller":"traceutil/trace.go:171","msg":"trace[979805241] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"835.888932ms","start":"2026-02-14T04:55:26.134745Z","end":"2026-02-14T04:55:26.970634Z","steps":["trace[979805241] 'process raft request' (duration: 828.699724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:26.970674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:26.134742Z","time spent":"835.915922ms","remote":"127.0.0.1:55790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-14T04:55:26.970765Z","caller":"traceutil/trace.go:171","msg":"trace[1172733358] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"835.984343ms","start":"2026-02-14T04:55:26.134773Z","end":"2026-02-14T04:55:26.970757Z","steps":["trace[1172733358] 'process raft request' (duration: 828.829176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:26.97081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:26.134768Z","time spent":"836.028924ms","remote":"127.0.0.1:55790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-14T04:55:26.9709Z","caller":"traceutil/trace.go:171","msg":"trace[305657518] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"836.103086ms","start":"2026-02-14T04:55:26.134791Z","end":"2026-02-14T04:55:26.970894Z","steps":["trace[305657518] 'process raft request' (duration: 828.846607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:26.970945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:26.134789Z","time spent":"836.124927ms","remote":"127.0.0.1:55790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-14T04:55:26.97106Z","caller":"traceutil/trace.go:171","msg":"trace[718372866] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"836.072466ms","start":"2026-02-14T04:55:26.134981Z","end":"2026-02-14T04:55:26.971054Z","steps":["trace[718372866] 'process raft request' (duration: 828.802386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:26.971119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:26.134977Z","time spent":"836.096936ms","remote":"127.0.0.1:55280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:55:26.971186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.156647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:2859"} {"level":"info","ts":"2026-02-14T04:55:26.971236Z","caller":"traceutil/trace.go:171","msg":"trace[1631784491] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:12; }","duration":"836.224289ms","start":"2026-02-14T04:55:26.135002Z","end":"2026-02-14T04:55:26.971226Z","steps":["trace[1631784491] 'agreement among raft nodes before linearized reading' (duration: 836.122496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:26.971285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:26.135Z","time spent":"836.254929ms","remote":"127.0.0.1:55396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":2883,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-14T04:55:26.97133Z","caller":"traceutil/trace.go:171","msg":"trace[605434845] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"783.596428ms","start":"2026-02-14T04:55:26.187726Z","end":"2026-02-14T04:55:26.971322Z","steps":["trace[605434845] 'process raft request' (duration: 776.307428ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:55:26.971411Z","caller":"traceutil/trace.go:171","msg":"trace[175129398] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"308.9671ms","start":"2026-02-14T04:55:26.662436Z","end":"2026-02-14T04:55:26.971403Z","steps":["trace[175129398] 'process raft request' (duration: 308.642343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:26.971385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:26.187709Z","time spent":"783.655968ms","remote":"127.0.0.1:55396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2847,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-14T04:55:26.971449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:26.662415Z","time spent":"309.013072ms","remote":"127.0.0.1:55790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-14T04:55:26.971535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.254196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-14T04:55:26.971551Z","caller":"traceutil/trace.go:171","msg":"trace[1540818959] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:12; }","duration":"792.286047ms","start":"2026-02-14T04:55:26.179259Z","end":"2026-02-14T04:55:26.971546Z","steps":["trace[1540818959] 'agreement among raft nodes before linearized reading' (duration: 792.260317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:26.971566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:26.179252Z","time spent":"792.310288ms","remote":"127.0.0.1:55330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-02-14T04:55:26.971628Z","caller":"traceutil/trace.go:171","msg":"trace[1596304106] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"776.278997ms","start":"2026-02-14T04:55:26.195339Z","end":"2026-02-14T04:55:26.971618Z","steps":["trace[1596304106] 'process raft request' (duration: 768.741942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:26.971654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.795869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-14T04:55:26.971672Z","caller":"traceutil/trace.go:171","msg":"trace[1237826254] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:12; }","duration":"792.837729ms","start":"2026-02-14T04:55:26.178828Z","end":"2026-02-14T04:55:26.971666Z","steps":["trace[1237826254] 'agreement among raft nodes before linearized reading' (duration: 792.813348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:26.971663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:26.195325Z","time spent":"776.321708ms","remote":"127.0.0.1:55480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-14T04:55:26.971708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.431925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-14T04:55:26.971723Z","caller":"traceutil/trace.go:171","msg":"trace[954011168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:12; }","duration":"832.452696ms","start":"2026-02-14T04:55:26.139266Z","end":"2026-02-14T04:55:26.971719Z","steps":["trace[954011168] 'agreement among raft nodes before linearized reading' (duration: 832.430356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:26.971739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:26.139264Z","time spent":"832.471336ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-14T04:55:26.971819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.556528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-14T04:55:26.971836Z","caller":"traceutil/trace.go:171","msg":"trace[1431008749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:12; }","duration":"832.583968ms","start":"2026-02-14T04:55:26.139246Z","end":"2026-02-14T04:55:26.97183Z","steps":["trace[1431008749] 'agreement among raft nodes before linearized reading' (duration: 832.559808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:26.971858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:26.139241Z","time spent":"832.612339ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-14T04:55:26.972098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.821877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-14T04:55:26.972128Z","caller":"traceutil/trace.go:171","msg":"trace[1114393825] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12; }","duration":"839.870049ms","start":"2026-02-14T04:55:26.132246Z","end":"2026-02-14T04:55:26.972116Z","steps":["trace[1114393825] 'agreement among raft nodes before linearized reading' (duration: 839.806667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:26.972165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:26.132234Z","time spent":"839.92644ms","remote":"127.0.0.1:55232","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-02-14T04:55:26.971687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:26.178817Z","time spent":"792.866499ms","remote":"127.0.0.1:55330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-14T04:55:26.973965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.882897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-14T04:55:26.973994Z","caller":"traceutil/trace.go:171","msg":"trace[1779781069] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:12; }","duration":"838.921328ms","start":"2026-02-14T04:55:26.135065Z","end":"2026-02-14T04:55:26.973987Z","steps":["trace[1779781069] 'agreement among raft nodes before linearized reading' (duration: 838.859256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:26.974068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:26.135062Z","time spent":"838.998699ms","remote":"127.0.0.1:55304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-02-14T04:55:53.796104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.087339ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-14T04:55:53.79626Z","caller":"traceutil/trace.go:171","msg":"trace[83950169] linearizableReadLoop","detail":"{readStateIndex:483; appliedIndex:482; }","duration":"564.151577ms","start":"2026-02-14T04:55:53.232067Z","end":"2026-02-14T04:55:53.796219Z","steps":["trace[83950169] 'read index received' (duration: 147.772852ms)","trace[83950169] 'applied index is now lower than readState.Index' (duration: 416.377335ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:55:53.796385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.305601ms","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-14T04:55:53.796395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.324458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-14T04:55:53.796414Z","caller":"traceutil/trace.go:171","msg":"trace[1728102449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:468; }","duration":"564.362671ms","start":"2026-02-14T04:55:53.23204Z","end":"2026-02-14T04:55:53.796403Z","steps":["trace[1728102449] 'agreement among raft nodes before linearized reading' (duration: 564.28566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:53.796451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:53.232028Z","time spent":"564.413744ms","remote":"127.0.0.1:55496","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":"warn","ts":"2026-02-14T04:55:53.796499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.582838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4062"} {"level":"info","ts":"2026-02-14T04:55:53.796526Z","caller":"traceutil/trace.go:171","msg":"trace[1906463611] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:468; }","duration":"187.63129ms","start":"2026-02-14T04:55:53.608885Z","end":"2026-02-14T04:55:53.796517Z","steps":["trace[1906463611] 'agreement among raft nodes before linearized reading' (duration: 187.585149ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:55:53.796449Z","caller":"traceutil/trace.go:171","msg":"trace[2135218899] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:468; }","duration":"558.39048ms","start":"2026-02-14T04:55:53.238044Z","end":"2026-02-14T04:55:53.796435Z","steps":["trace[2135218899] 'agreement among raft nodes before linearized reading' (duration: 558.295547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:53.796674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:53.23804Z","time spent":"558.626045ms","remote":"127.0.0.1:55496","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-14T04:55:56.819728Z","caller":"traceutil/trace.go:171","msg":"trace[13540070] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"354.509828ms","start":"2026-02-14T04:55:56.465195Z","end":"2026-02-14T04:55:56.819705Z","steps":["trace[13540070] 'process raft request' (duration: 300.516354ms)","trace[13540070] 'compare' (duration: 53.85358ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:55:56.819857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:56.465186Z","time spent":"354.59216ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:55:58.299915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.575811ms","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-14T04:55:58.299982Z","caller":"traceutil/trace.go:171","msg":"trace[1445081979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:478; }","duration":"452.670043ms","start":"2026-02-14T04:55:57.847296Z","end":"2026-02-14T04:55:58.299966Z","steps":["trace[1445081979] 'range keys from in-memory index tree' (duration: 452.5194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:58.300018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:57.847269Z","time spent":"452.741045ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-14T04:55:58.300225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.378501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-14T04:55:58.30026Z","caller":"traceutil/trace.go:171","msg":"trace[269747579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:478; }","duration":"436.424332ms","start":"2026-02-14T04:55:57.863822Z","end":"2026-02-14T04:55:58.300246Z","steps":["trace[269747579] 'range keys from in-memory index tree' (duration: 436.32664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:55:58.300289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:55:57.863813Z","time spent":"436.469223ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-14T04:55:58.300779Z","caller":"traceutil/trace.go:171","msg":"trace[1805177869] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"290.551761ms","start":"2026-02-14T04:55:58.010214Z","end":"2026-02-14T04:55:58.300766Z","steps":["trace[1805177869] 'process raft request' (duration: 289.377266ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:56:00.527745Z","caller":"traceutil/trace.go:171","msg":"trace[1435742685] linearizableReadLoop","detail":"{readStateIndex:501; appliedIndex:499; }","duration":"154.031752ms","start":"2026-02-14T04:56:00.373702Z","end":"2026-02-14T04:56:00.527734Z","steps":["trace[1435742685] 'read index received' (duration: 35.522351ms)","trace[1435742685] 'applied index is now lower than readState.Index' (duration: 118.508891ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:56:00.527919Z","caller":"traceutil/trace.go:171","msg":"trace[1648742109] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"208.905783ms","start":"2026-02-14T04:56:00.319007Z","end":"2026-02-14T04:56:00.527913Z","steps":["trace[1648742109] 'process raft request' (duration: 208.644098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:00.528023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.310128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:56:00.528038Z","caller":"traceutil/trace.go:171","msg":"trace[1627854486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:485; }","duration":"154.358219ms","start":"2026-02-14T04:56:00.373676Z","end":"2026-02-14T04:56:00.528034Z","steps":["trace[1627854486] 'agreement among raft nodes before linearized reading' (duration: 154.323818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:01.59355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.943202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6534"} {"level":"info","ts":"2026-02-14T04:56:01.593626Z","caller":"traceutil/trace.go:171","msg":"trace[1800941405] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:491; }","duration":"423.048824ms","start":"2026-02-14T04:56:01.170559Z","end":"2026-02-14T04:56:01.593608Z","steps":["trace[1800941405] 'range keys from in-memory index tree' (duration: 422.8654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:01.593667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:01.170545Z","time spent":"423.113226ms","remote":"127.0.0.1:55316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":6558,"request content":"key:\"/registry/configmaps/kube-system/cilium-config\" "} {"level":"info","ts":"2026-02-14T04:56:03.989244Z","caller":"traceutil/trace.go:171","msg":"trace[1007657526] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"223.005368ms","start":"2026-02-14T04:56:03.766221Z","end":"2026-02-14T04:56:03.989227Z","steps":["trace[1007657526] 'process raft request' (duration: 222.906376ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:56:03.989437Z","caller":"traceutil/trace.go:171","msg":"trace[887960841] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:530; }","duration":"216.886011ms","start":"2026-02-14T04:56:03.772521Z","end":"2026-02-14T04:56:03.989407Z","steps":["trace[887960841] 'read index received' (duration: 216.880411ms)","trace[887960841] 'applied index is now lower than readState.Index' (duration: 4.61µs)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:56:03.989815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.265929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-pxrjx\" ","response":"range_response_count:1 size:4822"} {"level":"info","ts":"2026-02-14T04:56:03.989833Z","caller":"traceutil/trace.go:171","msg":"trace[1730954581] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-pxrjx; range_end:; response_count:1; response_revision:513; }","duration":"217.33592ms","start":"2026-02-14T04:56:03.772491Z","end":"2026-02-14T04:56:03.989827Z","steps":["trace[1730954581] 'agreement among raft nodes before linearized reading' (duration: 217.002643ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:56:03.997457Z","caller":"traceutil/trace.go:171","msg":"trace[332123289] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"119.289918ms","start":"2026-02-14T04:56:03.878152Z","end":"2026-02-14T04:56:03.997442Z","steps":["trace[332123289] 'process raft request' (duration: 119.238917ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:56:03.997783Z","caller":"traceutil/trace.go:171","msg":"trace[575868032] transaction","detail":"{read_only:false; number_of_response:0; response_revision:513; }","duration":"223.270544ms","start":"2026-02-14T04:56:03.774506Z","end":"2026-02-14T04:56:03.997777Z","steps":["trace[575868032] 'process raft request' (duration: 222.840264ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:56:20.029226Z","caller":"traceutil/trace.go:171","msg":"trace[1656887334] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"114.259745ms","start":"2026-02-14T04:56:19.914947Z","end":"2026-02-14T04:56:20.029206Z","steps":["trace[1656887334] 'process raft request' (duration: 114.058591ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:56:22.170456Z","caller":"traceutil/trace.go:171","msg":"trace[184112213] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"133.035033ms","start":"2026-02-14T04:56:22.037394Z","end":"2026-02-14T04:56:22.170429Z","steps":["trace[184112213] 'process raft request' (duration: 132.934201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:25.15133Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548332465982156,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-14T04:56:25.56858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.393722ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-14T04:56:25.568727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.607029ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:56:25.571349Z","caller":"traceutil/trace.go:171","msg":"trace[1981909640] linearizableReadLoop","detail":"{readStateIndex:717; appliedIndex:716; }","duration":"921.026727ms","start":"2026-02-14T04:56:24.650309Z","end":"2026-02-14T04:56:25.571336Z","steps":["trace[1981909640] 'read index received' (duration: 515.709198ms)","trace[1981909640] 'applied index is now lower than readState.Index' (duration: 405.316279ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:56:25.571406Z","caller":"traceutil/trace.go:171","msg":"trace[1133993687] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"922.651644ms","start":"2026-02-14T04:56:24.648748Z","end":"2026-02-14T04:56:25.5714Z","steps":["trace[1133993687] 'process raft request' (duration: 517.321074ms)","trace[1133993687] 'compare' (duration: 402.473636ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:56:25.571446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:24.648732Z","time spent":"922.693524ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:56:25.57127Z","caller":"traceutil/trace.go:171","msg":"trace[1612772210] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:693; }","duration":"505.090931ms","start":"2026-02-14T04:56:25.066159Z","end":"2026-02-14T04:56:25.57125Z","steps":["trace[1612772210] 'range keys from in-memory index tree' (duration: 502.378211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:25.571648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.342375ms","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-14T04:56:25.571669Z","caller":"traceutil/trace.go:171","msg":"trace[658539723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:694; }","duration":"921.386185ms","start":"2026-02-14T04:56:24.650277Z","end":"2026-02-14T04:56:25.571664Z","steps":["trace[658539723] 'agreement among raft nodes before linearized reading' (duration: 921.268523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:25.571684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:24.650267Z","time spent":"921.413606ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-14T04:56:25.571772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.136521ms","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-02-14T04:56:25.57179Z","caller":"traceutil/trace.go:171","msg":"trace[734127120] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:694; }","duration":"605.190662ms","start":"2026-02-14T04:56:24.966594Z","end":"2026-02-14T04:56:25.571785Z","steps":["trace[734127120] 'agreement among raft nodes before linearized reading' (duration: 605.146521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:25.571801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:24.966576Z","time spent":"605.222412ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-14T04:56:25.572163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.101668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:56:25.572186Z","caller":"traceutil/trace.go:171","msg":"trace[1430316911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:694; }","duration":"196.15996ms","start":"2026-02-14T04:56:25.376021Z","end":"2026-02-14T04:56:25.572181Z","steps":["trace[1430316911] 'agreement among raft nodes before linearized reading' (duration: 196.125229ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:56:25.743309Z","caller":"traceutil/trace.go:171","msg":"trace[567480204] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"166.345635ms","start":"2026-02-14T04:56:25.576799Z","end":"2026-02-14T04:56:25.743145Z","steps":["trace[567480204] 'process raft request' (duration: 116.785462ms)","trace[567480204] 'compare' (duration: 49.45524ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:56:25.744079Z","caller":"traceutil/trace.go:171","msg":"trace[118155906] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"165.720161ms","start":"2026-02-14T04:56:25.578342Z","end":"2026-02-14T04:56:25.744062Z","steps":["trace[118155906] 'process raft request' (duration: 165.656549ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:56:38.862012Z","caller":"traceutil/trace.go:171","msg":"trace[1429743692] linearizableReadLoop","detail":"{readStateIndex:758; appliedIndex:757; }","duration":"488.474773ms","start":"2026-02-14T04:56:38.373519Z","end":"2026-02-14T04:56:38.861993Z","steps":["trace[1429743692] 'read index received' (duration: 488.348231ms)","trace[1429743692] 'applied index is now lower than readState.Index' (duration: 125.902µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:56:38.862238Z","caller":"traceutil/trace.go:171","msg":"trace[405651680] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"600.742829ms","start":"2026-02-14T04:56:38.261488Z","end":"2026-02-14T04:56:38.862231Z","steps":["trace[405651680] 'process raft request' (duration: 600.419001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:38.862313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:38.261477Z","time spent":"600.77979ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:56:38.862463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.948874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:56:38.862485Z","caller":"traceutil/trace.go:171","msg":"trace[1346271529] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:732; }","duration":"489.001176ms","start":"2026-02-14T04:56:38.373476Z","end":"2026-02-14T04:56:38.862477Z","steps":["trace[1346271529] 'agreement among raft nodes before linearized reading' (duration: 488.965455ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:38.862502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:38.373456Z","time spent":"489.042707ms","remote":"127.0.0.1:55218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T04:56:38.862664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.673473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-14T04:56:38.862681Z","caller":"traceutil/trace.go:171","msg":"trace[967687172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:732; }","duration":"213.716674ms","start":"2026-02-14T04:56:38.648959Z","end":"2026-02-14T04:56:38.862676Z","steps":["trace[967687172] 'agreement among raft nodes before linearized reading' (duration: 213.639932ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:56:41.154399Z","caller":"traceutil/trace.go:171","msg":"trace[1611520940] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"282.0219ms","start":"2026-02-14T04:56:40.872355Z","end":"2026-02-14T04:56:41.154377Z","steps":["trace[1611520940] 'process raft request' (duration: 281.941798ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:56:41.17406Z","caller":"traceutil/trace.go:171","msg":"trace[1511112331] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"301.075015ms","start":"2026-02-14T04:56:40.872973Z","end":"2026-02-14T04:56:41.174048Z","steps":["trace[1511112331] 'process raft request' (duration: 301.021564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:41.174146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:40.872963Z","time spent":"301.128786ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:56:44.604973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.956221ms","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-14T04:56:44.605032Z","caller":"traceutil/trace.go:171","msg":"trace[140613957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:746; }","duration":"731.047574ms","start":"2026-02-14T04:56:43.873969Z","end":"2026-02-14T04:56:44.605017Z","steps":["trace[140613957] 'range keys from in-memory index tree' (duration: 730.811738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:44.605059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:43.873954Z","time spent":"731.099224ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-14T04:56:44.605201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.452219ms","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-02-14T04:56:44.605251Z","caller":"traceutil/trace.go:171","msg":"trace[1121237978] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:746; }","duration":"723.53428ms","start":"2026-02-14T04:56:43.881703Z","end":"2026-02-14T04:56:44.605238Z","steps":["trace[1121237978] 'range keys from in-memory index tree' (duration: 723.325217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:44.605303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:43.881691Z","time spent":"723.606223ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-14T04:56:49.684652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.916105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:56:49.684703Z","caller":"traceutil/trace.go:171","msg":"trace[279916980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:761; }","duration":"309.992268ms","start":"2026-02-14T04:56:49.374699Z","end":"2026-02-14T04:56:49.684691Z","steps":["trace[279916980] 'range keys from in-memory index tree' (duration: 309.862974ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:49.684724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:49.374689Z","time spent":"310.030458ms","remote":"127.0.0.1:55218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T04:56:49.684832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.192853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-14T04:56:49.684901Z","caller":"traceutil/trace.go:171","msg":"trace[1363777251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:761; }","duration":"311.303466ms","start":"2026-02-14T04:56:49.373579Z","end":"2026-02-14T04:56:49.684883Z","steps":["trace[1363777251] 'range keys from in-memory index tree' (duration: 311.03405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:49.684941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:49.373562Z","time spent":"311.369357ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-14T04:56:50.028924Z","caller":"traceutil/trace.go:171","msg":"trace[993867952] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:789; }","duration":"336.497504ms","start":"2026-02-14T04:56:49.6924Z","end":"2026-02-14T04:56:50.028897Z","steps":["trace[993867952] 'read index received' (duration: 336.34611ms)","trace[993867952] 'applied index is now lower than readState.Index' (duration: 149.824µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:56:50.029106Z","caller":"traceutil/trace.go:171","msg":"trace[128835289] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"337.123218ms","start":"2026-02-14T04:56:49.691967Z","end":"2026-02-14T04:56:50.02909Z","steps":["trace[128835289] 'process raft request' (duration: 336.794501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:50.029117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.698038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-14T04:56:50.029152Z","caller":"traceutil/trace.go:171","msg":"trace[153465610] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:762; }","duration":"336.76614ms","start":"2026-02-14T04:56:49.692376Z","end":"2026-02-14T04:56:50.029142Z","steps":["trace[153465610] 'agreement among raft nodes before linearized reading' (duration: 336.618387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:50.029184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:49.692365Z","time spent":"336.809761ms","remote":"127.0.0.1:55496","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":"warn","ts":"2026-02-14T04:56:50.0292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:49.69195Z","time spent":"337.19503ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:56:50.225701Z","caller":"traceutil/trace.go:171","msg":"trace[1292196111] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"190.70607ms","start":"2026-02-14T04:56:50.034966Z","end":"2026-02-14T04:56:50.225672Z","steps":["trace[1292196111] 'process raft request' (duration: 131.464412ms)","trace[1292196111] 'compare' (duration: 58.666026ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:56:50.880233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.699185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:56:50.880568Z","caller":"traceutil/trace.go:171","msg":"trace[258885134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:763; }","duration":"505.080994ms","start":"2026-02-14T04:56:50.375468Z","end":"2026-02-14T04:56:50.880549Z","steps":["trace[258885134] 'range keys from in-memory index tree' (duration: 504.555652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:50.880362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.286529ms","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-02-14T04:56:50.881309Z","caller":"traceutil/trace.go:171","msg":"trace[1861350868] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:763; }","duration":"252.23091ms","start":"2026-02-14T04:56:50.62903Z","end":"2026-02-14T04:56:50.881261Z","steps":["trace[1861350868] 'range keys from in-memory index tree' (duration: 251.122715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:50.880439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.862459ms","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-14T04:56:50.881494Z","caller":"traceutil/trace.go:171","msg":"trace[343906229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:763; }","duration":"250.952253ms","start":"2026-02-14T04:56:50.630532Z","end":"2026-02-14T04:56:50.881485Z","steps":["trace[343906229] 'range keys from in-memory index tree' (duration: 249.734775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:50.88124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:50.37545Z","time spent":"505.286618ms","remote":"127.0.0.1:55218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-14T04:56:51.281793Z","caller":"traceutil/trace.go:171","msg":"trace[2053524090] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"395.873479ms","start":"2026-02-14T04:56:50.885905Z","end":"2026-02-14T04:56:51.281779Z","steps":["trace[2053524090] 'process raft request' (duration: 395.765567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:51.281925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:50.88589Z","time spent":"395.9548ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:56:51.617689Z","caller":"traceutil/trace.go:171","msg":"trace[804825185] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:792; }","duration":"730.696057ms","start":"2026-02-14T04:56:50.886303Z","end":"2026-02-14T04:56:51.616999Z","steps":["trace[804825185] 'read index received' (duration: 395.92218ms)","trace[804825185] 'applied index is now lower than readState.Index' (duration: 334.770947ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:56:51.617957Z","caller":"traceutil/trace.go:171","msg":"trace[1788753787] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"731.767751ms","start":"2026-02-14T04:56:50.886176Z","end":"2026-02-14T04:56:51.617944Z","steps":["trace[1788753787] 'process raft request' (duration: 711.318736ms)","trace[1788753787] 'compare' (duration: 19.420022ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:56:51.61805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:50.886168Z","time spent":"731.818001ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:56:51.618134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.828189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-14T04:56:51.6182Z","caller":"traceutil/trace.go:171","msg":"trace[2038839778] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:765; }","duration":"351.949002ms","start":"2026-02-14T04:56:51.266234Z","end":"2026-02-14T04:56:51.618183Z","steps":["trace[2038839778] 'agreement among raft nodes before linearized reading' (duration: 351.734207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:51.618244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:51.266216Z","time spent":"352.019163ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-14T04:56:51.618355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.045646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:56:51.618389Z","caller":"traceutil/trace.go:171","msg":"trace[374500821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:765; }","duration":"732.094447ms","start":"2026-02-14T04:56:50.886284Z","end":"2026-02-14T04:56:51.618379Z","steps":["trace[374500821] 'agreement among raft nodes before linearized reading' (duration: 732.040546ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:51.618419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:50.886279Z","time spent":"732.134639ms","remote":"127.0.0.1:55232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-14T04:57:07.601774Z","caller":"traceutil/trace.go:171","msg":"trace[302580558] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"175.861758ms","start":"2026-02-14T04:57:07.425892Z","end":"2026-02-14T04:57:07.601754Z","steps":["trace[302580558] 'process raft request' (duration: 175.763086ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:07.85138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.486228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-14T04:57:07.85145Z","caller":"traceutil/trace.go:171","msg":"trace[849282747] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:860; }","duration":"157.59379ms","start":"2026-02-14T04:57:07.69384Z","end":"2026-02-14T04:57:07.851433Z","steps":["trace[849282747] 'range keys from in-memory index tree' (duration: 157.368336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:07.851634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.170124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.212\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-14T04:57:07.851662Z","caller":"traceutil/trace.go:171","msg":"trace[1360590608] range","detail":"{range_begin:/registry/masterleases/162.253.55.212; range_end:; response_count:1; response_revision:860; }","duration":"153.209015ms","start":"2026-02-14T04:57:07.698443Z","end":"2026-02-14T04:57:07.851652Z","steps":["trace[1360590608] 'range keys from in-memory index tree' (duration: 153.101833ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:08.256466Z","caller":"traceutil/trace.go:171","msg":"trace[1037902471] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"398.090486ms","start":"2026-02-14T04:57:07.858361Z","end":"2026-02-14T04:57:08.256451Z","steps":["trace[1037902471] 'process raft request' (duration: 398.060095ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:08.256579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:07.858345Z","time spent":"398.167277ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:57:08.256776Z","caller":"traceutil/trace.go:171","msg":"trace[807176208] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"400.822685ms","start":"2026-02-14T04:57:07.855947Z","end":"2026-02-14T04:57:08.25677Z","steps":["trace[807176208] 'process raft request' (duration: 377.971637ms)","trace[807176208] 'compare' (duration: 22.424838ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:57:08.256809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:07.855925Z","time spent":"400.867496ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:57:08.307185Z","caller":"traceutil/trace.go:171","msg":"trace[1249753774] linearizableReadLoop","detail":"{readStateIndex:897; appliedIndex:894; }","duration":"204.626795ms","start":"2026-02-14T04:57:08.102538Z","end":"2026-02-14T04:57:08.307164Z","steps":["trace[1249753774] 'read index received' (duration: 131.474762ms)","trace[1249753774] 'applied index is now lower than readState.Index' (duration: 73.151143ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:57:08.307385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.83104ms","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-14T04:57:08.307415Z","caller":"traceutil/trace.go:171","msg":"trace[1589455892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:863; }","duration":"204.898521ms","start":"2026-02-14T04:57:08.10251Z","end":"2026-02-14T04:57:08.307409Z","steps":["trace[1589455892] 'agreement among raft nodes before linearized reading' (duration: 204.728116ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:08.307653Z","caller":"traceutil/trace.go:171","msg":"trace[1381237859] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"365.714251ms","start":"2026-02-14T04:57:07.941932Z","end":"2026-02-14T04:57:08.307646Z","steps":["trace[1381237859] 'process raft request' (duration: 365.151148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:08.307706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:07.941917Z","time spent":"365.757563ms","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:57:09.655014Z","caller":"traceutil/trace.go:171","msg":"trace[126686654] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"199.107705ms","start":"2026-02-14T04:57:09.455892Z","end":"2026-02-14T04:57:09.654999Z","steps":["trace[126686654] 'process raft request' (duration: 198.744146ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:09.788691Z","caller":"traceutil/trace.go:171","msg":"trace[1771448066] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"130.688465ms","start":"2026-02-14T04:57:09.657988Z","end":"2026-02-14T04:57:09.788676Z","steps":["trace[1771448066] 'process raft request' (duration: 95.887808ms)","trace[1771448066] 'compare' (duration: 34.434719ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:57:10.136864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.975001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:10.136937Z","caller":"traceutil/trace.go:171","msg":"trace[1392257128] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:867; }","duration":"165.081584ms","start":"2026-02-14T04:57:09.971836Z","end":"2026-02-14T04:57:10.136917Z","steps":["trace[1392257128] 'count revisions from in-memory index tree' (duration: 164.92412ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:16.488185Z","caller":"traceutil/trace.go:171","msg":"trace[768595816] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:918; }","duration":"203.93377ms","start":"2026-02-14T04:57:16.284237Z","end":"2026-02-14T04:57:16.488171Z","steps":["trace[768595816] 'read index received' (duration: 203.825597ms)","trace[768595816] 'applied index is now lower than readState.Index' (duration: 107.383µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:57:16.48826Z","caller":"traceutil/trace.go:171","msg":"trace[1521970838] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"204.931991ms","start":"2026-02-14T04:57:16.283323Z","end":"2026-02-14T04:57:16.488255Z","steps":["trace[1521970838] 'process raft request' (duration: 204.746837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:16.488425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.531044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-14T04:57:16.488472Z","caller":"traceutil/trace.go:171","msg":"trace[170319296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:884; }","duration":"137.614356ms","start":"2026-02-14T04:57:16.350846Z","end":"2026-02-14T04:57:16.488461Z","steps":["trace[170319296] 'agreement among raft nodes before linearized reading' (duration: 137.492483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:16.488576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.325858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-14T04:57:16.488596Z","caller":"traceutil/trace.go:171","msg":"trace[1014167998] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:884; }","duration":"204.366859ms","start":"2026-02-14T04:57:16.284223Z","end":"2026-02-14T04:57:16.48859Z","steps":["trace[1014167998] 'agreement among raft nodes before linearized reading' (duration: 204.291407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:16.488691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.33568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-14T04:57:16.488706Z","caller":"traceutil/trace.go:171","msg":"trace[425409577] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:884; }","duration":"103.3521ms","start":"2026-02-14T04:57:16.38535Z","end":"2026-02-14T04:57:16.488702Z","steps":["trace[425409577] 'agreement among raft nodes before linearized reading' (duration: 103.305909ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:20.02627Z","caller":"traceutil/trace.go:171","msg":"trace[2011246800] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"199.166675ms","start":"2026-02-14T04:57:19.827085Z","end":"2026-02-14T04:57:20.026252Z","steps":["trace[2011246800] 'process raft request' (duration: 199.070583ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:20.858553Z","caller":"traceutil/trace.go:171","msg":"trace[1881896976] linearizableReadLoop","detail":"{readStateIndex:931; appliedIndex:930; }","duration":"480.803136ms","start":"2026-02-14T04:57:20.377736Z","end":"2026-02-14T04:57:20.858539Z","steps":["trace[1881896976] 'read index received' (duration: 480.717845ms)","trace[1881896976] 'applied index is now lower than readState.Index' (duration: 84.211µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:57:20.858629Z","caller":"traceutil/trace.go:171","msg":"trace[2083410304] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"803.761576ms","start":"2026-02-14T04:57:20.054862Z","end":"2026-02-14T04:57:20.858624Z","steps":["trace[2083410304] 'process raft request' (duration: 803.587003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:20.858688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:20.054847Z","time spent":"803.794368ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:57:20.858767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.032001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:20.858782Z","caller":"traceutil/trace.go:171","msg":"trace[1697677377] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:895; }","duration":"481.069803ms","start":"2026-02-14T04:57:20.377707Z","end":"2026-02-14T04:57:20.858777Z","steps":["trace[1697677377] 'agreement among raft nodes before linearized reading' (duration: 481.043002ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:20.858795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:20.377693Z","time spent":"481.099803ms","remote":"127.0.0.1:55218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T04:57:20.858937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.927267ms","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-14T04:57:20.85895Z","caller":"traceutil/trace.go:171","msg":"trace[1772117382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:895; }","duration":"343.971968ms","start":"2026-02-14T04:57:20.514974Z","end":"2026-02-14T04:57:20.858946Z","steps":["trace[1772117382] 'agreement among raft nodes before linearized reading' (duration: 343.916017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:20.858962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:20.514955Z","time spent":"344.003228ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-14T04:57:20.859045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.109188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-14T04:57:20.859061Z","caller":"traceutil/trace.go:171","msg":"trace[836814757] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:895; }","duration":"275.14581ms","start":"2026-02-14T04:57:20.58391Z","end":"2026-02-14T04:57:20.859056Z","steps":["trace[836814757] 'agreement among raft nodes before linearized reading' (duration: 275.107109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:20.859024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.305003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-14T04:57:20.859103Z","caller":"traceutil/trace.go:171","msg":"trace[1934223687] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:895; }","duration":"128.440476ms","start":"2026-02-14T04:57:20.730644Z","end":"2026-02-14T04:57:20.859085Z","steps":["trace[1934223687] 'agreement among raft nodes before linearized reading' (duration: 128.305143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:20.859138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.255241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-14T04:57:20.859152Z","caller":"traceutil/trace.go:171","msg":"trace[1730614475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:895; }","duration":"275.289683ms","start":"2026-02-14T04:57:20.583858Z","end":"2026-02-14T04:57:20.859148Z","steps":["trace[1730614475] 'agreement among raft nodes before linearized reading' (duration: 275.252832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:20.85947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.613824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-14T04:57:20.859963Z","caller":"traceutil/trace.go:171","msg":"trace[867514554] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:895; }","duration":"290.127555ms","start":"2026-02-14T04:57:20.569829Z","end":"2026-02-14T04:57:20.859956Z","steps":["trace[867514554] 'agreement among raft nodes before linearized reading' (duration: 289.610454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:21.083364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.529689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:21.083425Z","caller":"traceutil/trace.go:171","msg":"trace[476477536] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:895; }","duration":"219.61991ms","start":"2026-02-14T04:57:20.863788Z","end":"2026-02-14T04:57:21.083407Z","steps":["trace[476477536] 'range keys from in-memory index tree' (duration: 219.480357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:21.083517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.644196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:57:21.08364Z","caller":"traceutil/trace.go:171","msg":"trace[899534778] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:931; }","duration":"192.978551ms","start":"2026-02-14T04:57:20.890652Z","end":"2026-02-14T04:57:21.083631Z","steps":["trace[899534778] 'read index received' (duration: 32.169311ms)","trace[899534778] 'applied index is now lower than readState.Index' (duration: 160.80853ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:57:21.083649Z","caller":"traceutil/trace.go:171","msg":"trace[1797428201] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"219.62743ms","start":"2026-02-14T04:57:20.86401Z","end":"2026-02-14T04:57:21.083638Z","steps":["trace[1797428201] 'process raft request' (duration: 58.80663ms)","trace[1797428201] 'compare' (duration: 160.493873ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:57:21.083686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.034412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:21.083699Z","caller":"traceutil/trace.go:171","msg":"trace[1713697055] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:899; }","duration":"193.065713ms","start":"2026-02-14T04:57:20.890629Z","end":"2026-02-14T04:57:21.083695Z","steps":["trace[1713697055] 'agreement among raft nodes before linearized reading' (duration: 193.028612ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:21.083812Z","caller":"traceutil/trace.go:171","msg":"trace[1666044347] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"219.244853ms","start":"2026-02-14T04:57:20.864561Z","end":"2026-02-14T04:57:21.083806Z","steps":["trace[1666044347] 'process raft request' (duration: 219.013808ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:21.083944Z","caller":"traceutil/trace.go:171","msg":"trace[1582263104] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"217.757079ms","start":"2026-02-14T04:57:20.866182Z","end":"2026-02-14T04:57:21.083939Z","steps":["trace[1582263104] 'process raft request' (duration: 217.415872ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:21.086543Z","caller":"traceutil/trace.go:171","msg":"trace[406920802] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"220.200263ms","start":"2026-02-14T04:57:20.866333Z","end":"2026-02-14T04:57:21.086533Z","steps":["trace[406920802] 'process raft request' (duration: 217.281289ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:37.179534Z","caller":"traceutil/trace.go:171","msg":"trace[1939304313] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"108.488571ms","start":"2026-02-14T04:57:37.071021Z","end":"2026-02-14T04:57:37.179509Z","steps":["trace[1939304313] 'process raft request' (duration: 108.300987ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:37.362377Z","caller":"traceutil/trace.go:171","msg":"trace[1896206333] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"147.02633ms","start":"2026-02-14T04:57:37.215297Z","end":"2026-02-14T04:57:37.362324Z","steps":["trace[1896206333] 'process raft request' (duration: 146.985799ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:37.362798Z","caller":"traceutil/trace.go:171","msg":"trace[1531614163] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"173.940087ms","start":"2026-02-14T04:57:37.188844Z","end":"2026-02-14T04:57:37.362784Z","steps":["trace[1531614163] 'process raft request' (duration: 173.389645ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:37.362985Z","caller":"traceutil/trace.go:171","msg":"trace[80778826] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"174.979749ms","start":"2026-02-14T04:57:37.187993Z","end":"2026-02-14T04:57:37.362973Z","steps":["trace[80778826] 'process raft request' (duration: 75.174607ms)","trace[80778826] 'compare' (duration: 98.893642ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:57:38.869824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.168973377s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.212\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-14T04:57:38.869879Z","caller":"traceutil/trace.go:171","msg":"trace[1013439641] range","detail":"{range_begin:/registry/masterleases/162.253.55.212; range_end:; response_count:1; response_revision:1009; }","duration":"1.169067718s","start":"2026-02-14T04:57:37.700798Z","end":"2026-02-14T04:57:38.869866Z","steps":["trace[1013439641] 'range keys from in-memory index tree' (duration: 1.168824232s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:38.869905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:37.70078Z","time spent":"1.16911943s","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.212\" "} {"level":"warn","ts":"2026-02-14T04:57:38.870114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.664323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-14T04:57:38.870138Z","caller":"traceutil/trace.go:171","msg":"trace[1415753169] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1009; }","duration":"780.716734ms","start":"2026-02-14T04:57:38.089411Z","end":"2026-02-14T04:57:38.870127Z","steps":["trace[1415753169] 'range keys from in-memory index tree' (duration: 780.577911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:38.870154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:38.089391Z","time spent":"780.759375ms","remote":"127.0.0.1:55496","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-02-14T04:57:38.870375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.906142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:38.870393Z","caller":"traceutil/trace.go:171","msg":"trace[471438274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1009; }","duration":"278.956763ms","start":"2026-02-14T04:57:38.591431Z","end":"2026-02-14T04:57:38.870387Z","steps":["trace[471438274] 'range keys from in-memory index tree' (duration: 278.8356ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:39.195727Z","caller":"traceutil/trace.go:171","msg":"trace[1259478786] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"320.868635ms","start":"2026-02-14T04:57:38.874836Z","end":"2026-02-14T04:57:39.195705Z","steps":["trace[1259478786] 'process raft request' (duration: 320.737962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:39.195867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:38.874821Z","time spent":"320.975107ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:57:39.20037Z","caller":"traceutil/trace.go:171","msg":"trace[913531597] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"167.756662ms","start":"2026-02-14T04:57:39.032601Z","end":"2026-02-14T04:57:39.200358Z","steps":["trace[913531597] 'process raft request' (duration: 167.555028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:46.962654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.378851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:00:46.962737Z","caller":"traceutil/trace.go:171","msg":"trace[1207112686] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1812; }","duration":"243.474854ms","start":"2026-02-14T05:00:46.71925Z","end":"2026-02-14T05:00:46.962725Z","steps":["trace[1207112686] 'count revisions from in-memory index tree' (duration: 243.32036ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:00:47.457816Z","caller":"traceutil/trace.go:171","msg":"trace[1190670213] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"173.925145ms","start":"2026-02-14T05:00:47.283876Z","end":"2026-02-14T05:00:47.457801Z","steps":["trace[1190670213] 'process raft request' (duration: 173.581967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:47.585102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.023115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:00:47.585174Z","caller":"traceutil/trace.go:171","msg":"trace[1457774548] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1813; }","duration":"108.126377ms","start":"2026-02-14T05:00:47.477032Z","end":"2026-02-14T05:00:47.585158Z","steps":["trace[1457774548] 'range keys from in-memory index tree' (duration: 107.927303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:48.665838Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548332465984985,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-14T05:00:49.274956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.182926ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T05:00:49.275035Z","caller":"traceutil/trace.go:171","msg":"trace[2082813255] linearizableReadLoop","detail":"{readStateIndex:1895; appliedIndex:1894; }","duration":"1.109563482s","start":"2026-02-14T05:00:48.165459Z","end":"2026-02-14T05:00:49.275023Z","steps":["trace[2082813255] 'read index received' (duration: 715.245484ms)","trace[2082813255] 'applied index is now lower than readState.Index' (duration: 394.317068ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:00:49.276235Z","caller":"traceutil/trace.go:171","msg":"trace[636798568] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"1.210870931s","start":"2026-02-14T05:00:48.064366Z","end":"2026-02-14T05:00:49.275237Z","steps":["trace[636798568] 'process raft request' (duration: 816.359578ms)","trace[636798568] 'compare' (duration: 393.942771ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:00:49.276294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:48.064351Z","time spent":"1.211917454s","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:00:49.276607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111145537s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-14T05:00:49.276644Z","caller":"traceutil/trace.go:171","msg":"trace[606498601] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1816; }","duration":"1.111220799s","start":"2026-02-14T05:00:48.165416Z","end":"2026-02-14T05:00:49.276637Z","steps":["trace[606498601] 'agreement among raft nodes before linearized reading' (duration: 1.111099605s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:49.277189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:48.165397Z","time spent":"1.11126429s","remote":"127.0.0.1:55496","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":"warn","ts":"2026-02-14T05:00:49.277509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.11034551s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-02-14T05:00:49.277538Z","caller":"traceutil/trace.go:171","msg":"trace[882871154] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1816; }","duration":"1.110396092s","start":"2026-02-14T05:00:48.167135Z","end":"2026-02-14T05:00:49.277531Z","steps":["trace[882871154] 'agreement among raft nodes before linearized reading' (duration: 1.1103094s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:49.277558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:48.167125Z","time spent":"1.110428942s","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-14T05:00:49.277885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.729299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:00:49.277911Z","caller":"traceutil/trace.go:171","msg":"trace[1265830615] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1816; }","duration":"360.822992ms","start":"2026-02-14T05:00:48.917082Z","end":"2026-02-14T05:00:49.277905Z","steps":["trace[1265830615] 'agreement among raft nodes before linearized reading' (duration: 360.781901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:49.27793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:48.917062Z","time spent":"360.864062ms","remote":"127.0.0.1:55218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T05:00:49.278228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.045389ms","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-14T05:00:49.278255Z","caller":"traceutil/trace.go:171","msg":"trace[1959472337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1816; }","duration":"785.11713ms","start":"2026-02-14T05:00:48.493132Z","end":"2026-02-14T05:00:49.278249Z","steps":["trace[1959472337] 'agreement among raft nodes before linearized reading' (duration: 785.024428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:49.278276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:48.493112Z","time spent":"785.157551ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-14T05:00:49.27873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.498371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-14T05:00:49.278756Z","caller":"traceutil/trace.go:171","msg":"trace[921915267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1816; }","duration":"875.569953ms","start":"2026-02-14T05:00:48.40318Z","end":"2026-02-14T05:00:49.27875Z","steps":["trace[921915267] 'agreement among raft nodes before linearized reading' (duration: 875.490221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:49.278777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:48.403152Z","time spent":"875.619225ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-14T05:00:49.279173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.751472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-14T05:00:49.279211Z","caller":"traceutil/trace.go:171","msg":"trace[121232744] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1816; }","duration":"948.809983ms","start":"2026-02-14T05:00:48.330395Z","end":"2026-02-14T05:00:49.279205Z","steps":["trace[121232744] 'agreement among raft nodes before linearized reading' (duration: 948.729411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:49.279231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:48.330389Z","time spent":"948.837684ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-14T05:00:49.279579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.303715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:00:49.279608Z","caller":"traceutil/trace.go:171","msg":"trace[1360667306] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1816; }","duration":"949.374146ms","start":"2026-02-14T05:00:48.330227Z","end":"2026-02-14T05:00:49.279601Z","steps":["trace[1360667306] 'agreement among raft nodes before linearized reading' (duration: 949.326855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:49.279703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:48.330207Z","time spent":"949.415767ms","remote":"127.0.0.1:55762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-14T05:00:49.279951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.068970033s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:00:49.27998Z","caller":"traceutil/trace.go:171","msg":"trace[300736370] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1816; }","duration":"1.069042374s","start":"2026-02-14T05:00:48.210931Z","end":"2026-02-14T05:00:49.279973Z","steps":["trace[300736370] 'agreement among raft nodes before linearized reading' (duration: 1.068994313s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:49.28Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:00:48.210912Z","time spent":"1.069082815s","remote":"127.0.0.1:43234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "}