{"level":"warn","ts":"2026-02-03T17:21:18.140995Z","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-03T17:21:18.141112Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.33:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.33:2380","--initial-cluster=instance=https://199.204.45.33:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.33:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.33: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-03T17:21:18.141204Z","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-03T17:21:18.141225Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.33:2380"]} {"level":"info","ts":"2026-02-03T17:21:18.141264Z","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-03T17:21:18.141942Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"]} {"level":"info","ts":"2026-02-03T17:21:18.142046Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.33: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-03T17:21:18.149802Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.504892ms"} {"level":"info","ts":"2026-02-03T17:21:18.161533Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e5729930cf7e1f","cluster-id":"14aeb8c3ec66b749"} {"level":"info","ts":"2026-02-03T17:21:18.161602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=()"} {"level":"info","ts":"2026-02-03T17:21:18.161622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 0"} {"level":"info","ts":"2026-02-03T17:21:18.161629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e5729930cf7e1f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-03T17:21:18.161635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 1"} {"level":"info","ts":"2026-02-03T17:21:18.161676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"} {"level":"warn","ts":"2026-02-03T17:21:18.171057Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-03T17:21:18.200499Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-03T17:21:18.204423Z","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-03T17:21:18.206886Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e5729930cf7e1f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-03T17:21:18.210293Z","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-03T17:21:18.211305Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e5729930cf7e1f","initial-advertise-peer-urls":["https://199.204.45.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-03T17:21:18.211612Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-03T17:21:18.211945Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e5729930cf7e1f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-03T17:21:18.21219Z","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-03T17:21:18.212397Z","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-03T17:21:18.213622Z","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-03T17:21:18.212519Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.33:2380"} {"level":"info","ts":"2026-02-03T17:21:18.214052Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.33:2380"} {"level":"info","ts":"2026-02-03T17:21:18.217166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"} {"level":"info","ts":"2026-02-03T17:21:18.221539Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","added-peer-id":"f1e5729930cf7e1f","added-peer-peer-urls":["https://199.204.45.33:2380"]} {"level":"info","ts":"2026-02-03T17:21:18.86301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f is starting a new election at term 1"} {"level":"info","ts":"2026-02-03T17:21:18.863216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became pre-candidate at term 1"} {"level":"info","ts":"2026-02-03T17:21:18.863277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgPreVoteResp from f1e5729930cf7e1f at term 1"} {"level":"info","ts":"2026-02-03T17:21:18.863334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became candidate at term 2"} {"level":"info","ts":"2026-02-03T17:21:18.863372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgVoteResp from f1e5729930cf7e1f at term 2"} {"level":"info","ts":"2026-02-03T17:21:18.863413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became leader at term 2"} {"level":"info","ts":"2026-02-03T17:21:18.863445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e5729930cf7e1f elected leader f1e5729930cf7e1f at term 2"} {"level":"info","ts":"2026-02-03T17:21:19.143258Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e5729930cf7e1f","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.33:2379]}","request-path":"/0/members/f1e5729930cf7e1f/attributes","cluster-id":"14aeb8c3ec66b749","publish-timeout":"7s"} {"level":"info","ts":"2026-02-03T17:21:19.143577Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T17:21:19.143673Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T17:21:19.144153Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T17:21:19.144352Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-03T17:21:19.144387Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-03T17:21:19.146064Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.33:2379"} {"level":"info","ts":"2026-02-03T17:21:19.152199Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-03T17:21:19.20517Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T17:21:19.205279Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T17:21:19.205339Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T17:21:26.205544Z","caller":"traceutil/trace.go:171","msg":"trace[541479559] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"803.493743ms","start":"2026-02-03T17:21:25.402033Z","end":"2026-02-03T17:21:26.205527Z","steps":["trace[541479559] 'process raft request' (duration: 803.463022ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:26.205889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:21:25.402009Z","time spent":"803.611058ms","remote":"127.0.0.1:49846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-03T17:21:26.205565Z","caller":"traceutil/trace.go:171","msg":"trace[489936370] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"849.461944ms","start":"2026-02-03T17:21:25.35609Z","end":"2026-02-03T17:21:26.205552Z","steps":["trace[489936370] 'process raft request' (duration: 842.691249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:26.206065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:21:25.356083Z","time spent":"849.850639ms","remote":"127.0.0.1:49832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7213,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:21:31.575457Z","caller":"traceutil/trace.go:171","msg":"trace[1972676950] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"284.009833ms","start":"2026-02-03T17:21:31.291425Z","end":"2026-02-03T17:21:31.575435Z","steps":["trace[1972676950] 'process raft request' (duration: 283.829477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:37.135875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.482925ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:4017"} {"level":"info","ts":"2026-02-03T17:21:37.136153Z","caller":"traceutil/trace.go:171","msg":"trace[875644918] linearizableReadLoop","detail":"{readStateIndex:404; appliedIndex:401; }","duration":"431.617385ms","start":"2026-02-03T17:21:36.704503Z","end":"2026-02-03T17:21:37.13612Z","steps":["trace[875644918] 'read index received' (duration: 428.375883ms)","trace[875644918] 'applied index is now lower than readState.Index' (duration: 3.240812ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:21:37.136235Z","caller":"traceutil/trace.go:171","msg":"trace[379700527] transaction","detail":"{read_only:false; number_of_response:1; response_revision:388; }","duration":"521.106737ms","start":"2026-02-03T17:21:36.615099Z","end":"2026-02-03T17:21:37.136205Z","steps":["trace[379700527] 'process raft request' (duration: 70.027181ms)","trace[379700527] 'compare' (duration: 450.411143ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:21:37.136283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:21:36.615078Z","time spent":"521.17984ms","remote":"127.0.0.1:49832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":55,"response count":0,"response size":4040,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-02-03T17:21:37.13662Z","caller":"traceutil/trace.go:171","msg":"trace[763676808] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"516.305349ms","start":"2026-02-03T17:21:36.620286Z","end":"2026-02-03T17:21:37.136591Z","steps":["trace[763676808] 'process raft request' (duration: 515.704978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:37.136763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:21:36.62027Z","time spent":"516.416443ms","remote":"127.0.0.1:49738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":721,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-03T17:21:37.138284Z","caller":"traceutil/trace.go:171","msg":"trace[660653717] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"507.508ms","start":"2026-02-03T17:21:36.630768Z","end":"2026-02-03T17:21:37.138276Z","steps":["trace[660653717] 'process raft request' (duration: 505.313554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:37.138336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:21:36.630748Z","time spent":"507.560662ms","remote":"127.0.0.1:50070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3818,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:21:37.138481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.975257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:21:37.138502Z","caller":"traceutil/trace.go:171","msg":"trace[1129159136] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:390; }","duration":"434.060579ms","start":"2026-02-03T17:21:36.704435Z","end":"2026-02-03T17:21:37.138495Z","steps":["trace[1129159136] 'agreement among raft nodes before linearized reading' (duration: 434.025798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:37.138521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:21:36.704418Z","time spent":"434.099421ms","remote":"127.0.0.1:49650","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-03T17:21:37.138769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.299298ms","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-03T17:21:37.138798Z","caller":"traceutil/trace.go:171","msg":"trace[1673472708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:390; }","duration":"296.36758ms","start":"2026-02-03T17:21:36.842423Z","end":"2026-02-03T17:21:37.13879Z","steps":["trace[1673472708] 'agreement among raft nodes before linearized reading' (duration: 296.191004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:37.140051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.389869ms","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-03T17:21:37.140083Z","caller":"traceutil/trace.go:171","msg":"trace[299838672] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:390; }","duration":"116.460923ms","start":"2026-02-03T17:21:37.023614Z","end":"2026-02-03T17:21:37.140074Z","steps":["trace[299838672] 'agreement among raft nodes before linearized reading' (duration: 116.407511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:37.140216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.996847ms","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-03T17:21:37.14024Z","caller":"traceutil/trace.go:171","msg":"trace[1205864905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:390; }","duration":"296.031999ms","start":"2026-02-03T17:21:36.844201Z","end":"2026-02-03T17:21:37.140233Z","steps":["trace[1205864905] 'agreement among raft nodes before linearized reading' (duration: 295.990608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:37.967041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.445136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:21:37.967308Z","caller":"traceutil/trace.go:171","msg":"trace[64761140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:409; }","duration":"262.726656ms","start":"2026-02-03T17:21:37.704537Z","end":"2026-02-03T17:21:37.967264Z","steps":["trace[64761140] 'range keys from in-memory index tree' (duration: 262.366993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:42.724949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:21:42.397262Z","time spent":"327.684812ms","remote":"127.0.0.1:49692","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-03T17:21:43.398737Z","caller":"traceutil/trace.go:171","msg":"trace[2083027198] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:434; }","duration":"216.989416ms","start":"2026-02-03T17:21:43.181728Z","end":"2026-02-03T17:21:43.398717Z","steps":["trace[2083027198] 'read index received' (duration: 208.532011ms)","trace[2083027198] 'applied index is now lower than readState.Index' (duration: 8.456615ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:21:43.398829Z","caller":"traceutil/trace.go:171","msg":"trace[1773116598] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"671.945811ms","start":"2026-02-03T17:21:42.726876Z","end":"2026-02-03T17:21:43.398822Z","steps":["trace[1773116598] 'process raft request' (duration: 663.434045ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:43.398913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:21:42.726867Z","time spent":"671.984522ms","remote":"127.0.0.1:49692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:21:43.399103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.235709ms","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-03T17:21:43.399135Z","caller":"traceutil/trace.go:171","msg":"trace[1765879820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:419; }","duration":"187.294501ms","start":"2026-02-03T17:21:43.211831Z","end":"2026-02-03T17:21:43.399126Z","steps":["trace[1765879820] 'agreement among raft nodes before linearized reading' (duration: 187.219949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:43.399238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.523762ms","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-03T17:21:43.399257Z","caller":"traceutil/trace.go:171","msg":"trace[403644947] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:419; }","duration":"217.577135ms","start":"2026-02-03T17:21:43.181675Z","end":"2026-02-03T17:21:43.399252Z","steps":["trace[403644947] 'agreement among raft nodes before linearized reading' (duration: 217.542344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:43.399257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.2887ms","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-03T17:21:43.399278Z","caller":"traceutil/trace.go:171","msg":"trace[2126576397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:419; }","duration":"183.353073ms","start":"2026-02-03T17:21:43.215919Z","end":"2026-02-03T17:21:43.399272Z","steps":["trace[2126576397] 'agreement among raft nodes before linearized reading' (duration: 183.316081ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:21:43.551498Z","caller":"traceutil/trace.go:171","msg":"trace[1894222211] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"146.300479ms","start":"2026-02-03T17:21:43.405181Z","end":"2026-02-03T17:21:43.551481Z","steps":["trace[1894222211] 'process raft request' (duration: 146.201646ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:21:43.551737Z","caller":"traceutil/trace.go:171","msg":"trace[487811068] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:436; }","duration":"145.458933ms","start":"2026-02-03T17:21:43.406237Z","end":"2026-02-03T17:21:43.551696Z","steps":["trace[487811068] 'read index received' (duration: 145.443713ms)","trace[487811068] 'applied index is now lower than readState.Index' (duration: 12.19µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:21:43.552048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.761993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-03T17:21:43.55209Z","caller":"traceutil/trace.go:171","msg":"trace[160244010] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:420; }","duration":"145.843375ms","start":"2026-02-03T17:21:43.406235Z","end":"2026-02-03T17:21:43.552078Z","steps":["trace[160244010] 'agreement among raft nodes before linearized reading' (duration: 145.6806ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:21:43.552685Z","caller":"traceutil/trace.go:171","msg":"trace[789270564] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"142.569869ms","start":"2026-02-03T17:21:43.410106Z","end":"2026-02-03T17:21:43.552676Z","steps":["trace[789270564] 'process raft request' (duration: 142.532107ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:21:43.552841Z","caller":"traceutil/trace.go:171","msg":"trace[1303450498] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"144.240944ms","start":"2026-02-03T17:21:43.408594Z","end":"2026-02-03T17:21:43.552835Z","steps":["trace[1303450498] 'process raft request' (duration: 144.003066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:52.642552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.193778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.33\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-03T17:21:52.642607Z","caller":"traceutil/trace.go:171","msg":"trace[1418113433] range","detail":"{range_begin:/registry/masterleases/199.204.45.33; range_end:; response_count:1; response_revision:477; }","duration":"246.281601ms","start":"2026-02-03T17:21:52.396313Z","end":"2026-02-03T17:21:52.642594Z","steps":["trace[1418113433] 'range keys from in-memory index tree' (duration: 246.120695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:52.642769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.03897ms","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-03T17:21:52.642813Z","caller":"traceutil/trace.go:171","msg":"trace[1069294606] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:477; }","duration":"976.101623ms","start":"2026-02-03T17:21:51.666698Z","end":"2026-02-03T17:21:52.6428Z","steps":["trace[1069294606] 'range keys from in-memory index tree' (duration: 975.99337ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:52.642844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:21:51.666687Z","time spent":"976.149504ms","remote":"127.0.0.1:49894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-03T17:21:52.642962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.055685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:21:52.642979Z","caller":"traceutil/trace.go:171","msg":"trace[492263794] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:477; }","duration":"939.113016ms","start":"2026-02-03T17:21:51.70386Z","end":"2026-02-03T17:21:52.642973Z","steps":["trace[492263794] 'range keys from in-memory index tree' (duration: 939.015744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:52.642992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:21:51.703848Z","time spent":"939.140657ms","remote":"127.0.0.1:49650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-03T17:21:52.772152Z","caller":"traceutil/trace.go:171","msg":"trace[1441472902] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"123.538896ms","start":"2026-02-03T17:21:52.648602Z","end":"2026-02-03T17:21:52.772141Z","steps":["trace[1441472902] 'process raft request' (duration: 123.470804ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:21:53.917964Z","caller":"traceutil/trace.go:171","msg":"trace[1140923123] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"306.205333ms","start":"2026-02-03T17:21:53.611722Z","end":"2026-02-03T17:21:53.917928Z","steps":["trace[1140923123] 'process raft request' (duration: 306.10722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:53.918077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:21:53.611712Z","time spent":"306.296855ms","remote":"127.0.0.1:49894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:21:53.918409Z","caller":"traceutil/trace.go:171","msg":"trace[1073387559] linearizableReadLoop","detail":"{readStateIndex:498; appliedIndex:498; }","duration":"255.653402ms","start":"2026-02-03T17:21:53.66275Z","end":"2026-02-03T17:21:53.918403Z","steps":["trace[1073387559] 'read index received' (duration: 255.650552ms)","trace[1073387559] 'applied index is now lower than readState.Index' (duration: 2.17µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:21:53.918461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.674752ms","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-03T17:21:53.918476Z","caller":"traceutil/trace.go:171","msg":"trace[1892569962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:480; }","duration":"255.698003ms","start":"2026-02-03T17:21:53.662774Z","end":"2026-02-03T17:21:53.918472Z","steps":["trace[1892569962] 'agreement among raft nodes before linearized reading' (duration: 255.666372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:53.918559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.808466ms","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-03T17:21:53.918573Z","caller":"traceutil/trace.go:171","msg":"trace[1834501487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:480; }","duration":"255.845137ms","start":"2026-02-03T17:21:53.662723Z","end":"2026-02-03T17:21:53.918568Z","steps":["trace[1834501487] 'agreement among raft nodes before linearized reading' (duration: 255.822457ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:53.918626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.579827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:21:53.918638Z","caller":"traceutil/trace.go:171","msg":"trace[1320717552] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:480; }","duration":"214.613348ms","start":"2026-02-03T17:21:53.70402Z","end":"2026-02-03T17:21:53.918633Z","steps":["trace[1320717552] 'agreement among raft nodes before linearized reading' (duration: 214.595138ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:21:54.066524Z","caller":"traceutil/trace.go:171","msg":"trace[519754491] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"143.466561ms","start":"2026-02-03T17:21:53.923039Z","end":"2026-02-03T17:21:54.066506Z","steps":["trace[519754491] 'process raft request' (duration: 143.251875ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:21:54.067203Z","caller":"traceutil/trace.go:171","msg":"trace[1507490089] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"143.292857ms","start":"2026-02-03T17:21:53.923902Z","end":"2026-02-03T17:21:54.067194Z","steps":["trace[1507490089] 'process raft request' (duration: 143.228205ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:21:57.068476Z","caller":"traceutil/trace.go:171","msg":"trace[981648021] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"284.959668ms","start":"2026-02-03T17:21:56.783497Z","end":"2026-02-03T17:21:57.068457Z","steps":["trace[981648021] 'process raft request' (duration: 284.854874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:00.584782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"879.490468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:22:00.584841Z","caller":"traceutil/trace.go:171","msg":"trace[1805828314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"879.609022ms","start":"2026-02-03T17:21:59.705219Z","end":"2026-02-03T17:22:00.584828Z","steps":["trace[1805828314] 'range keys from in-memory index tree' (duration: 879.436927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:00.584888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:21:59.705207Z","time spent":"879.664213ms","remote":"127.0.0.1:49650","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-03T17:22:00.585088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.376414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-03T17:22:00.585116Z","caller":"traceutil/trace.go:171","msg":"trace[442617125] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:517; }","duration":"647.426856ms","start":"2026-02-03T17:21:59.93768Z","end":"2026-02-03T17:22:00.585107Z","steps":["trace[442617125] 'range keys from in-memory index tree' (duration: 647.310913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:00.585135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:21:59.937655Z","time spent":"647.475367ms","remote":"127.0.0.1:49894","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-03T17:22:00.585336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.374511ms","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-03T17:22:00.585356Z","caller":"traceutil/trace.go:171","msg":"trace[1198771277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:517; }","duration":"501.418033ms","start":"2026-02-03T17:22:00.083931Z","end":"2026-02-03T17:22:00.585349Z","steps":["trace[1198771277] 'range keys from in-memory index tree' (duration: 501.3248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:00.585373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:22:00.083919Z","time spent":"501.449483ms","remote":"127.0.0.1:49894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T17:22:00.585457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.705629ms","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-03T17:22:00.585474Z","caller":"traceutil/trace.go:171","msg":"trace[629836176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:517; }","duration":"494.73257ms","start":"2026-02-03T17:22:00.090736Z","end":"2026-02-03T17:22:00.585468Z","steps":["trace[629836176] 'range keys from in-memory index tree' (duration: 494.675718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:00.585494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:22:00.090731Z","time spent":"494.7584ms","remote":"127.0.0.1:49894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-03T17:22:00.585766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.90847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dfkwx\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-03T17:22:00.585802Z","caller":"traceutil/trace.go:171","msg":"trace[1388806893] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dfkwx; range_end:; response_count:1; response_revision:517; }","duration":"188.975932ms","start":"2026-02-03T17:22:00.396818Z","end":"2026-02-03T17:22:00.585794Z","steps":["trace[1388806893] 'range keys from in-memory index tree' (duration: 188.832528ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:01.175611Z","caller":"traceutil/trace.go:171","msg":"trace[1299157247] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:537; }","duration":"464.58379ms","start":"2026-02-03T17:22:00.711005Z","end":"2026-02-03T17:22:01.175589Z","steps":["trace[1299157247] 'read index received' (duration: 464.294182ms)","trace[1299157247] 'applied index is now lower than readState.Index' (duration: 288.678µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:22:01.175944Z","caller":"traceutil/trace.go:171","msg":"trace[1267459132] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"580.965226ms","start":"2026-02-03T17:22:00.594968Z","end":"2026-02-03T17:22:01.175933Z","steps":["trace[1267459132] 'process raft request' (duration: 580.411471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:01.176056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:22:00.594927Z","time spent":"581.064838ms","remote":"127.0.0.1:49894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:22:01.176226Z","caller":"traceutil/trace.go:171","msg":"trace[769128444] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"581.185701ms","start":"2026-02-03T17:22:00.595031Z","end":"2026-02-03T17:22:01.176217Z","steps":["trace[769128444] 'process raft request' (duration: 580.456192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:01.176276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:22:00.595021Z","time spent":"581.227162ms","remote":"127.0.0.1:49894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:22:01.176493Z","caller":"traceutil/trace.go:171","msg":"trace[2002339820] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"575.32613ms","start":"2026-02-03T17:22:00.601157Z","end":"2026-02-03T17:22:01.176483Z","steps":["trace[2002339820] 'process raft request' (duration: 574.364864ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:01.176552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:22:00.601139Z","time spent":"575.383541ms","remote":"127.0.0.1:49832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:22:01.176681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.689229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:22:01.176719Z","caller":"traceutil/trace.go:171","msg":"trace[670862536] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:521; }","duration":"465.782422ms","start":"2026-02-03T17:22:00.710927Z","end":"2026-02-03T17:22:01.176709Z","steps":["trace[670862536] 'agreement among raft nodes before linearized reading' (duration: 465.727901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:01.17675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:22:00.710886Z","time spent":"465.858484ms","remote":"127.0.0.1:49650","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-03T17:22:02.897361Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154253735449994,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T17:22:03.398235Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154253735449994,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T17:22:03.898697Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154253735449994,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T17:22:04.399486Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154253735449994,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T17:22:04.407665Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.51059593s","expected-duration":"1s"} {"level":"info","ts":"2026-02-03T17:22:04.40792Z","caller":"traceutil/trace.go:171","msg":"trace[26251026] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:541; }","duration":"2.011387978s","start":"2026-02-03T17:22:02.396514Z","end":"2026-02-03T17:22:04.407902Z","steps":["trace[26251026] 'read index received' (duration: 2.011244374s)","trace[26251026] 'applied index is now lower than readState.Index' (duration: 142.944µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:22:04.407989Z","caller":"traceutil/trace.go:171","msg":"trace[650786826] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"2.511057482s","start":"2026-02-03T17:22:01.896924Z","end":"2026-02-03T17:22:04.407982Z","steps":["trace[650786826] 'process raft request' (duration: 2.510847167s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:04.408073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:22:01.896907Z","time spent":"2.511100954s","remote":"127.0.0.1:49894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:22:04.40818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.790096006s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-03T17:22:04.40821Z","caller":"traceutil/trace.go:171","msg":"trace[578474226] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:523; }","duration":"1.790155027s","start":"2026-02-03T17:22:02.618042Z","end":"2026-02-03T17:22:04.408197Z","steps":["trace[578474226] 'agreement among raft nodes before linearized reading' (duration: 1.790096295s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:04.408231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:22:02.618025Z","time spent":"1.790201909s","remote":"127.0.0.1:49894","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-03T17:22:04.408318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.01180532s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.33\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-03T17:22:04.40833Z","caller":"traceutil/trace.go:171","msg":"trace[1731711685] range","detail":"{range_begin:/registry/masterleases/199.204.45.33; range_end:; response_count:1; response_revision:523; }","duration":"2.01183929s","start":"2026-02-03T17:22:02.396487Z","end":"2026-02-03T17:22:04.408326Z","steps":["trace[1731711685] 'agreement among raft nodes before linearized reading' (duration: 2.01181696s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:04.408341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:22:02.396475Z","time spent":"2.011864051s","remote":"127.0.0.1:49692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.33\" "} {"level":"warn","ts":"2026-02-03T17:22:04.408417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.707202279s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:22:04.408432Z","caller":"traceutil/trace.go:171","msg":"trace[739559024] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"1.707243041s","start":"2026-02-03T17:22:02.701185Z","end":"2026-02-03T17:22:04.408428Z","steps":["trace[739559024] 'agreement among raft nodes before linearized reading' (duration: 1.70721842s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:04.408429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.223557359s","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-03T17:22:04.408444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:22:02.701173Z","time spent":"1.707268321s","remote":"127.0.0.1:49650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-03T17:22:04.408457Z","caller":"traceutil/trace.go:171","msg":"trace[1750549430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:523; }","duration":"1.223606919s","start":"2026-02-03T17:22:03.184842Z","end":"2026-02-03T17:22:04.408449Z","steps":["trace[1750549430] 'agreement among raft nodes before linearized reading' (duration: 1.223551698s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:04.408478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:22:03.184829Z","time spent":"1.22364482s","remote":"127.0.0.1:49894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-03T17:22:04.408572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.126122295s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-03T17:22:04.408591Z","caller":"traceutil/trace.go:171","msg":"trace[158436877] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:523; }","duration":"1.126164427s","start":"2026-02-03T17:22:03.282421Z","end":"2026-02-03T17:22:04.408586Z","steps":["trace[158436877] 'agreement among raft nodes before linearized reading' (duration: 1.126132146s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:04.408608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:22:03.282408Z","time spent":"1.126195797s","remote":"127.0.0.1:49894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-03T17:22:04.408615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.223721463s","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-03T17:22:04.408631Z","caller":"traceutil/trace.go:171","msg":"trace[1346057080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:523; }","duration":"1.223737293s","start":"2026-02-03T17:22:03.184889Z","end":"2026-02-03T17:22:04.408626Z","steps":["trace[1346057080] 'agreement among raft nodes before linearized reading' (duration: 1.223706292s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:04.408645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:22:03.184877Z","time spent":"1.223765193s","remote":"127.0.0.1:49894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-03T17:22:04.637195Z","caller":"traceutil/trace.go:171","msg":"trace[1312953077] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"219.813113ms","start":"2026-02-03T17:22:04.41733Z","end":"2026-02-03T17:22:04.637143Z","steps":["trace[1312953077] 'process raft request' (duration: 219.71686ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:04.637265Z","caller":"traceutil/trace.go:171","msg":"trace[641678286] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:542; }","duration":"219.857193ms","start":"2026-02-03T17:22:04.41739Z","end":"2026-02-03T17:22:04.637247Z","steps":["trace[641678286] 'read index received' (duration: 61.490121ms)","trace[641678286] 'applied index is now lower than readState.Index' (duration: 158.366172ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:22:04.63737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.949996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:22:04.637391Z","caller":"traceutil/trace.go:171","msg":"trace[2112271611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:527; }","duration":"220.014198ms","start":"2026-02-03T17:22:04.41737Z","end":"2026-02-03T17:22:04.637385Z","steps":["trace[2112271611] 'agreement among raft nodes before linearized reading' (duration: 219.936146ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:04.63741Z","caller":"traceutil/trace.go:171","msg":"trace[1521922585] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"224.745542ms","start":"2026-02-03T17:22:04.412656Z","end":"2026-02-03T17:22:04.637401Z","steps":["trace[1521922585] 'process raft request' (duration: 216.023893ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:04.637588Z","caller":"traceutil/trace.go:171","msg":"trace[63235297] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"210.750114ms","start":"2026-02-03T17:22:04.426831Z","end":"2026-02-03T17:22:04.637581Z","steps":["trace[63235297] 'process raft request' (duration: 210.351024ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:04.637644Z","caller":"traceutil/trace.go:171","msg":"trace[247332834] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"212.661774ms","start":"2026-02-03T17:22:04.424977Z","end":"2026-02-03T17:22:04.637639Z","steps":["trace[247332834] 'process raft request' (duration: 212.13719ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:04.637908Z","caller":"traceutil/trace.go:171","msg":"trace[1377894088] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"139.683321ms","start":"2026-02-03T17:22:04.498218Z","end":"2026-02-03T17:22:04.637901Z","steps":["trace[1377894088] 'process raft request' (duration: 139.63889ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:05.543886Z","caller":"traceutil/trace.go:171","msg":"trace[642842557] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"117.254341ms","start":"2026-02-03T17:22:05.426617Z","end":"2026-02-03T17:22:05.543871Z","steps":["trace[642842557] 'process raft request' (duration: 117.176629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:06.787722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.13559ms","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-03T17:22:06.787789Z","caller":"traceutil/trace.go:171","msg":"trace[1733225982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:537; }","duration":"146.243093ms","start":"2026-02-03T17:22:06.64153Z","end":"2026-02-03T17:22:06.787773Z","steps":["trace[1733225982] 'range keys from in-memory index tree' (duration: 146.001927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:06.78797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.838363ms","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-03T17:22:06.787998Z","caller":"traceutil/trace.go:171","msg":"trace[1581693094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:537; }","duration":"145.884654ms","start":"2026-02-03T17:22:06.642104Z","end":"2026-02-03T17:22:06.787989Z","steps":["trace[1581693094] 'range keys from in-memory index tree' (duration: 145.762011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:06.788205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.013671ms","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-03T17:22:06.788232Z","caller":"traceutil/trace.go:171","msg":"trace[1972542268] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:537; }","duration":"104.141695ms","start":"2026-02-03T17:22:06.684082Z","end":"2026-02-03T17:22:06.788224Z","steps":["trace[1972542268] 'range keys from in-memory index tree' (duration: 103.889048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:06.788374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.569982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-03T17:22:06.788402Z","caller":"traceutil/trace.go:171","msg":"trace[116122339] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:537; }","duration":"100.611543ms","start":"2026-02-03T17:22:06.687782Z","end":"2026-02-03T17:22:06.788394Z","steps":["trace[116122339] 'range keys from in-memory index tree' (duration: 100.50312ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:08.110305Z","caller":"traceutil/trace.go:171","msg":"trace[449809185] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"394.927099ms","start":"2026-02-03T17:22:07.715359Z","end":"2026-02-03T17:22:08.110286Z","steps":["trace[449809185] 'process raft request' (duration: 394.810156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:08.110801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:22:07.71534Z","time spent":"395.38569ms","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-03T17:22:08.119542Z","caller":"traceutil/trace.go:171","msg":"trace[303806893] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"200.414409ms","start":"2026-02-03T17:22:07.91911Z","end":"2026-02-03T17:22:08.119524Z","steps":["trace[303806893] 'process raft request' (duration: 200.342798ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:08.621232Z","caller":"traceutil/trace.go:171","msg":"trace[1959053547] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"185.927622ms","start":"2026-02-03T17:22:08.435284Z","end":"2026-02-03T17:22:08.621212Z","steps":["trace[1959053547] 'process raft request' (duration: 185.877901ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:08.622063Z","caller":"traceutil/trace.go:171","msg":"trace[1500700221] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"186.734814ms","start":"2026-02-03T17:22:08.435312Z","end":"2026-02-03T17:22:08.622047Z","steps":["trace[1500700221] 'process raft request' (duration: 104.31064ms)","trace[1500700221] 'compare' (duration: 81.430478ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:22:08.622559Z","caller":"traceutil/trace.go:171","msg":"trace[12279025] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"104.780171ms","start":"2026-02-03T17:22:08.517761Z","end":"2026-02-03T17:22:08.622542Z","steps":["trace[12279025] 'process raft request' (duration: 104.74097ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:08.622762Z","caller":"traceutil/trace.go:171","msg":"trace[2142990721] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"176.600956ms","start":"2026-02-03T17:22:08.446156Z","end":"2026-02-03T17:22:08.622757Z","steps":["trace[2142990721] 'process raft request' (duration: 176.255437ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:09.295967Z","caller":"traceutil/trace.go:171","msg":"trace[696222885] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"144.270952ms","start":"2026-02-03T17:22:09.151677Z","end":"2026-02-03T17:22:09.295948Z","steps":["trace[696222885] 'process raft request' (duration: 105.860915ms)","trace[696222885] 'compare' (duration: 38.163591ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:22:10.056166Z","caller":"traceutil/trace.go:171","msg":"trace[290257596] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"290.586413ms","start":"2026-02-03T17:22:09.765563Z","end":"2026-02-03T17:22:10.056149Z","steps":["trace[290257596] 'read index received' (duration: 248.7785ms)","trace[290257596] 'applied index is now lower than readState.Index' (duration: 41.807353ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:22:10.056401Z","caller":"traceutil/trace.go:171","msg":"trace[1450599248] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"290.957812ms","start":"2026-02-03T17:22:09.765436Z","end":"2026-02-03T17:22:10.056394Z","steps":["trace[1450599248] 'process raft request' (duration: 248.942814ms)","trace[1450599248] 'compare' (duration: 41.703941ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:22:10.056556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.979093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-03T17:22:10.056577Z","caller":"traceutil/trace.go:171","msg":"trace[1596833150] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:597; }","duration":"291.011204ms","start":"2026-02-03T17:22:09.76556Z","end":"2026-02-03T17:22:10.056571Z","steps":["trace[1596833150] 'agreement among raft nodes before linearized reading' (duration: 290.931212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:10.056659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.410493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-03T17:22:10.056671Z","caller":"traceutil/trace.go:171","msg":"trace[1707985370] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:597; }","duration":"142.456394ms","start":"2026-02-03T17:22:09.914211Z","end":"2026-02-03T17:22:10.056667Z","steps":["trace[1707985370] 'agreement among raft nodes before linearized reading' (duration: 142.431894ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:11.020256Z","caller":"traceutil/trace.go:171","msg":"trace[1557382897] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"118.342328ms","start":"2026-02-03T17:22:10.901898Z","end":"2026-02-03T17:22:11.02024Z","steps":["trace[1557382897] 'process raft request' (duration: 118.241335ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:11.020573Z","caller":"traceutil/trace.go:171","msg":"trace[1582058330] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"118.623085ms","start":"2026-02-03T17:22:10.901944Z","end":"2026-02-03T17:22:11.020567Z","steps":["trace[1582058330] 'process raft request' (duration: 118.276526ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:11.020719Z","caller":"traceutil/trace.go:171","msg":"trace[520164527] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:621; }","duration":"106.908242ms","start":"2026-02-03T17:22:10.913803Z","end":"2026-02-03T17:22:11.020712Z","steps":["trace[520164527] 'read index received' (duration: 26.364167ms)","trace[520164527] 'applied index is now lower than readState.Index' (duration: 80.543235ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:22:11.020868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.051736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-03T17:22:11.020921Z","caller":"traceutil/trace.go:171","msg":"trace[902111207] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:604; }","duration":"107.111848ms","start":"2026-02-03T17:22:10.913772Z","end":"2026-02-03T17:22:11.020884Z","steps":["trace[902111207] 'agreement among raft nodes before linearized reading' (duration: 106.984615ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:11.021036Z","caller":"traceutil/trace.go:171","msg":"trace[535603063] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"119.053265ms","start":"2026-02-03T17:22:10.901978Z","end":"2026-02-03T17:22:11.021031Z","steps":["trace[535603063] 'process raft request' (duration: 118.565503ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:11.756173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.076279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dfkwx\" ","response":"range_response_count:1 size:18071"} {"level":"info","ts":"2026-02-03T17:22:11.756667Z","caller":"traceutil/trace.go:171","msg":"trace[534462507] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dfkwx; range_end:; response_count:1; response_revision:607; }","duration":"272.596101ms","start":"2026-02-03T17:22:11.484055Z","end":"2026-02-03T17:22:11.756651Z","steps":["trace[534462507] 'range keys from in-memory index tree' (duration: 271.977236ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:11.789238Z","caller":"traceutil/trace.go:171","msg":"trace[2071232329] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"153.152147ms","start":"2026-02-03T17:22:11.636071Z","end":"2026-02-03T17:22:11.789223Z","steps":["trace[2071232329] 'process raft request' (duration: 152.949892ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:11.843855Z","caller":"traceutil/trace.go:171","msg":"trace[609001436] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:628; }","duration":"129.391429ms","start":"2026-02-03T17:22:11.714447Z","end":"2026-02-03T17:22:11.843839Z","steps":["trace[609001436] 'read index received' (duration: 74.600044ms)","trace[609001436] 'applied index is now lower than readState.Index' (duration: 54.790885ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:22:11.844118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.653176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-03T17:22:11.844195Z","caller":"traceutil/trace.go:171","msg":"trace[1535257786] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:610; }","duration":"129.780409ms","start":"2026-02-03T17:22:11.714406Z","end":"2026-02-03T17:22:11.844187Z","steps":["trace[1535257786] 'agreement among raft nodes before linearized reading' (duration: 129.599034ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:11.844451Z","caller":"traceutil/trace.go:171","msg":"trace[1974203051] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"206.144798ms","start":"2026-02-03T17:22:11.6383Z","end":"2026-02-03T17:22:11.844445Z","steps":["trace[1974203051] 'process raft request' (duration: 205.40359ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:12.11593Z","caller":"traceutil/trace.go:171","msg":"trace[438089885] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"188.174848ms","start":"2026-02-03T17:22:11.927728Z","end":"2026-02-03T17:22:12.115903Z","steps":["trace[438089885] 'read index received' (duration: 121.013911ms)","trace[438089885] 'applied index is now lower than readState.Index' (duration: 67.160017ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:22:12.115991Z","caller":"traceutil/trace.go:171","msg":"trace[2026615888] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"285.232748ms","start":"2026-02-03T17:22:11.830753Z","end":"2026-02-03T17:22:12.115986Z","steps":["trace[2026615888] 'process raft request' (duration: 217.979079ms)","trace[2026615888] 'compare' (duration: 67.039283ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:22:12.116518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.775152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-02-03T17:22:12.116541Z","caller":"traceutil/trace.go:171","msg":"trace[659306520] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:611; }","duration":"188.830954ms","start":"2026-02-03T17:22:11.927702Z","end":"2026-02-03T17:22:12.116533Z","steps":["trace[659306520] 'agreement among raft nodes before linearized reading' (duration: 188.394953ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:12.133433Z","caller":"traceutil/trace.go:171","msg":"trace[1216984188] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"200.364955ms","start":"2026-02-03T17:22:11.933044Z","end":"2026-02-03T17:22:12.133409Z","steps":["trace[1216984188] 'process raft request' (duration: 199.967596ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:12.133449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.988379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-03T17:22:12.133499Z","caller":"traceutil/trace.go:171","msg":"trace[823183445] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:612; }","duration":"102.071821ms","start":"2026-02-03T17:22:12.031416Z","end":"2026-02-03T17:22:12.133487Z","steps":["trace[823183445] 'agreement among raft nodes before linearized reading' (duration: 101.972919ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:12.48753Z","caller":"traceutil/trace.go:171","msg":"trace[1469864060] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"118.282169ms","start":"2026-02-03T17:22:12.36923Z","end":"2026-02-03T17:22:12.487512Z","steps":["trace[1469864060] 'process raft request' (duration: 117.890769ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:12.487961Z","caller":"traceutil/trace.go:171","msg":"trace[831243760] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"114.923187ms","start":"2026-02-03T17:22:12.373033Z","end":"2026-02-03T17:22:12.487956Z","steps":["trace[831243760] 'process raft request' (duration: 114.281011ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:12.642685Z","caller":"traceutil/trace.go:171","msg":"trace[1995635537] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"119.712094ms","start":"2026-02-03T17:22:12.522887Z","end":"2026-02-03T17:22:12.642599Z","steps":["trace[1995635537] 'process raft request' (duration: 119.500629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:57.830681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.786473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:22:57.830777Z","caller":"traceutil/trace.go:171","msg":"trace[902278491] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:828; }","duration":"126.913955ms","start":"2026-02-03T17:22:57.703846Z","end":"2026-02-03T17:22:57.83076Z","steps":["trace[902278491] 'range keys from in-memory index tree' (duration: 126.731842ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:59.783067Z","caller":"traceutil/trace.go:171","msg":"trace[229382691] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"107.326699ms","start":"2026-02-03T17:22:59.675724Z","end":"2026-02-03T17:22:59.783051Z","steps":["trace[229382691] 'process raft request' (duration: 107.003573ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:23:22.159623Z","caller":"traceutil/trace.go:171","msg":"trace[1911083927] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"141.858123ms","start":"2026-02-03T17:23:22.017748Z","end":"2026-02-03T17:23:22.159606Z","steps":["trace[1911083927] 'process raft request' (duration: 111.560729ms)","trace[1911083927] 'compare' (duration: 30.000119ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:23:22.551952Z","caller":"traceutil/trace.go:171","msg":"trace[1911156993] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:958; }","duration":"151.998209ms","start":"2026-02-03T17:23:22.39993Z","end":"2026-02-03T17:23:22.551928Z","steps":["trace[1911156993] 'read index received' (duration: 84.780849ms)","trace[1911156993] 'applied index is now lower than readState.Index' (duration: 67.21612ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:23:22.552636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.68851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.33\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-03T17:23:22.552789Z","caller":"traceutil/trace.go:171","msg":"trace[1416946196] range","detail":"{range_begin:/registry/masterleases/199.204.45.33; range_end:; response_count:1; response_revision:919; }","duration":"152.883073ms","start":"2026-02-03T17:23:22.39989Z","end":"2026-02-03T17:23:22.552773Z","steps":["trace[1416946196] 'agreement among raft nodes before linearized reading' (duration: 152.320264ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:23:22.553236Z","caller":"traceutil/trace.go:171","msg":"trace[2081951575] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"204.495034ms","start":"2026-02-03T17:23:22.348706Z","end":"2026-02-03T17:23:22.553201Z","steps":["trace[2081951575] 'process raft request' (duration: 135.975024ms)","trace[2081951575] 'compare' (duration: 67.073527ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:23:23.116587Z","caller":"traceutil/trace.go:171","msg":"trace[246044854] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"109.441509ms","start":"2026-02-03T17:23:23.007118Z","end":"2026-02-03T17:23:23.11656Z","steps":["trace[246044854] 'process raft request' (duration: 21.741862ms)","trace[246044854] 'compare' (duration: 87.556465ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:23:23.126787Z","caller":"traceutil/trace.go:171","msg":"trace[484645474] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"117.816597ms","start":"2026-02-03T17:23:23.008948Z","end":"2026-02-03T17:23:23.126765Z","steps":["trace[484645474] 'process raft request' (duration: 117.591794ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:23:26.259718Z","caller":"traceutil/trace.go:171","msg":"trace[1586321195] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"225.35886ms","start":"2026-02-03T17:23:26.03434Z","end":"2026-02-03T17:23:26.259699Z","steps":["trace[1586321195] 'process raft request' (duration: 225.261249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:23:35.243544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.236176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:23:35.244596Z","caller":"traceutil/trace.go:171","msg":"trace[815701707] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:967; }","duration":"538.362262ms","start":"2026-02-03T17:23:34.706215Z","end":"2026-02-03T17:23:35.244577Z","steps":["trace[815701707] 'range keys from in-memory index tree' (duration: 537.114754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:23:35.245049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:23:34.706195Z","time spent":"538.838289ms","remote":"127.0.0.1:49650","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-03T17:23:35.245621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.460223ms","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-03T17:23:35.24572Z","caller":"traceutil/trace.go:171","msg":"trace[2087044732] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:967; }","duration":"386.558075ms","start":"2026-02-03T17:23:34.859147Z","end":"2026-02-03T17:23:35.245705Z","steps":["trace[2087044732] 'range keys from in-memory index tree' (duration: 386.329391ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:23:35.245817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:23:34.859092Z","time spent":"386.713517ms","remote":"127.0.0.1:49894","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":"info","ts":"2026-02-03T17:23:49.888899Z","caller":"traceutil/trace.go:171","msg":"trace[77932095] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1060; }","duration":"141.013447ms","start":"2026-02-03T17:23:49.747865Z","end":"2026-02-03T17:23:49.888878Z","steps":["trace[77932095] 'read index received' (duration: 140.845425ms)","trace[77932095] 'applied index is now lower than readState.Index' (duration: 167.002µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:23:49.889173Z","caller":"traceutil/trace.go:171","msg":"trace[1382280801] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"147.68599ms","start":"2026-02-03T17:23:49.741473Z","end":"2026-02-03T17:23:49.889159Z","steps":["trace[1382280801] 'process raft request' (duration: 147.291905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:23:49.889534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.644486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:pod-garbage-collector\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-02-03T17:23:49.889601Z","caller":"traceutil/trace.go:171","msg":"trace[736987344] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:pod-garbage-collector; range_end:; response_count:1; response_revision:1014; }","duration":"141.712507ms","start":"2026-02-03T17:23:49.747853Z","end":"2026-02-03T17:23:49.889565Z","steps":["trace[736987344] 'agreement among raft nodes before linearized reading' (duration: 141.619316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:23:49.889791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.063252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:persistent-volume-binder\" ","response":"range_response_count:1 size:771"} {"level":"info","ts":"2026-02-03T17:23:49.889822Z","caller":"traceutil/trace.go:171","msg":"trace[1017397931] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:persistent-volume-binder; range_end:; response_count:1; response_revision:1014; }","duration":"110.110323ms","start":"2026-02-03T17:23:49.779702Z","end":"2026-02-03T17:23:49.889812Z","steps":["trace[1017397931] 'agreement among raft nodes before linearized reading' (duration: 110.060012ms)"],"step_count":1}