{"level":"warn","ts":"2026-01-29T18:41:36.775238Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-29T18:41:36.775333Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113: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.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-01-29T18:41:36.775571Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-29T18:41:36.775588Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-01-29T18:41:36.77561Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-01-29T18:41:36.77643Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-01-29T18:41:36.776621Z","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.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-01-29T18:41:36.783092Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.193052ms"} {"level":"info","ts":"2026-01-29T18:41:36.805694Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-01-29T18:41:36.805761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-01-29T18:41:36.805781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-01-29T18:41:36.805789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-29T18:41:36.805801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-01-29T18:41:36.805865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-01-29T18:41:36.815396Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-29T18:41:36.817575Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-29T18:41:36.818869Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-01-29T18:41:36.823903Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-29T18:41:36.824464Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-29T18:41:36.824578Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-29T18:41:36.824613Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-29T18:41:36.824621Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-29T18:41:36.825203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-01-29T18:41:36.825298Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-01-29T18:41:36.83219Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-01-29T18:41:36.834076Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-01-29T18:41:36.834094Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-01-29T18:41:36.835005Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-29T18:41:36.835034Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-29T18:41:37.006758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-01-29T18:41:37.006798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-01-29T18:41:37.006815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-01-29T18:41:37.006826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-01-29T18:41:37.006961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-01-29T18:41:37.007002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-01-29T18:41:37.007028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-01-29T18:41:37.009185Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T18:41:37.012468Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-01-29T18:41:37.012495Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-29T18:41:37.012877Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-29T18:41:37.013415Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T18:41:37.013465Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T18:41:37.01352Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T18:41:37.013571Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-29T18:41:37.013582Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-29T18:41:37.014765Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-01-29T18:41:37.025082Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-29T18:41:48.620334Z","caller":"traceutil/trace.go:171","msg":"trace[278547989] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"131.030337ms","start":"2026-01-29T18:41:48.489263Z","end":"2026-01-29T18:41:48.620293Z","steps":["trace[278547989] 'process raft request' (duration: 130.244754ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:41:53.907416Z","caller":"traceutil/trace.go:171","msg":"trace[2087891211] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"188.747805ms","start":"2026-01-29T18:41:53.718651Z","end":"2026-01-29T18:41:53.907399Z","steps":["trace[2087891211] 'process raft request' (duration: 188.596986ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:41:54.835866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.658933ms","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-01-29T18:41:54.835941Z","caller":"traceutil/trace.go:171","msg":"trace[1049536783] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:377; }","duration":"487.788146ms","start":"2026-01-29T18:41:54.348137Z","end":"2026-01-29T18:41:54.835925Z","steps":["trace[1049536783] 'range keys from in-memory index tree' (duration: 487.528071ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:41:54.835993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:41:54.348106Z","time spent":"487.877065ms","remote":"127.0.0.1:51770","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-01-29T18:41:54.836175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.002861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4034"} {"level":"info","ts":"2026-01-29T18:41:54.836211Z","caller":"traceutil/trace.go:171","msg":"trace[962817351] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:377; }","duration":"455.064563ms","start":"2026-01-29T18:41:54.381138Z","end":"2026-01-29T18:41:54.836202Z","steps":["trace[962817351] 'range keys from in-memory index tree' (duration: 454.93875ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:41:54.836237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:41:54.381122Z","time spent":"455.109597ms","remote":"127.0.0.1:51954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4057,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-01-29T18:41:54.836404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.198288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:41:54.836437Z","caller":"traceutil/trace.go:171","msg":"trace[798772530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:377; }","duration":"216.25692ms","start":"2026-01-29T18:41:54.620171Z","end":"2026-01-29T18:41:54.836428Z","steps":["trace[798772530] 'range keys from in-memory index tree' (duration: 216.151575ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:04.153206Z","caller":"traceutil/trace.go:171","msg":"trace[356397431] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"204.232304ms","start":"2026-01-29T18:42:03.948955Z","end":"2026-01-29T18:42:04.153188Z","steps":["trace[356397431] 'process raft request' (duration: 204.137581ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:11.882862Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763060229253712,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-29T18:42:12.216646Z","caller":"traceutil/trace.go:171","msg":"trace[1684735001] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"938.84543ms","start":"2026-01-29T18:42:11.277784Z","end":"2026-01-29T18:42:12.216629Z","steps":["trace[1684735001] 'process raft request' (duration: 938.758624ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:12.216953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:11.277764Z","time spent":"938.936376ms","remote":"127.0.0.1:51542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-29T18:42:12.22591Z","caller":"traceutil/trace.go:171","msg":"trace[166961137] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:524; }","duration":"843.643575ms","start":"2026-01-29T18:42:11.382253Z","end":"2026-01-29T18:42:12.225896Z","steps":["trace[166961137] 'read index received' (duration: 834.358562ms)","trace[166961137] 'applied index is now lower than readState.Index' (duration: 9.284413ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:42:12.226066Z","caller":"traceutil/trace.go:171","msg":"trace[2044863366] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"346.072961ms","start":"2026-01-29T18:42:11.879985Z","end":"2026-01-29T18:42:12.226058Z","steps":["trace[2044863366] 'process raft request' (duration: 345.883919ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:12.226Z","caller":"traceutil/trace.go:171","msg":"trace[2087624902] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"745.070437ms","start":"2026-01-29T18:42:11.480923Z","end":"2026-01-29T18:42:12.225993Z","steps":["trace[2087624902] 'process raft request' (duration: 744.812757ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:12.226494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:11.480902Z","time spent":"745.567586ms","remote":"127.0.0.1:51542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-29T18:42:12.226145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:11.879964Z","time spent":"346.148887ms","remote":"127.0.0.1:51542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-29T18:42:12.226202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.946423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:42:12.227086Z","caller":"traceutil/trace.go:171","msg":"trace[744976296] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:512; }","duration":"844.814365ms","start":"2026-01-29T18:42:11.382226Z","end":"2026-01-29T18:42:12.22704Z","steps":["trace[744976296] 'agreement among raft nodes before linearized reading' (duration: 843.939622ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:12.227327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:11.382212Z","time spent":"845.040307ms","remote":"127.0.0.1:51588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"warn","ts":"2026-01-29T18:42:12.23138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.598412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-29T18:42:12.231449Z","caller":"traceutil/trace.go:171","msg":"trace[1563901601] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:513; }","duration":"738.694039ms","start":"2026-01-29T18:42:11.492741Z","end":"2026-01-29T18:42:12.231435Z","steps":["trace[1563901601] 'agreement among raft nodes before linearized reading' (duration: 738.16516ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:12.231478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:11.492732Z","time spent":"738.739906ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-29T18:42:12.233851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.242757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:42:12.23423Z","caller":"traceutil/trace.go:171","msg":"trace[1046034235] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:513; }","duration":"557.65136ms","start":"2026-01-29T18:42:11.676562Z","end":"2026-01-29T18:42:12.234214Z","steps":["trace[1046034235] 'agreement among raft nodes before linearized reading' (duration: 557.239607ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:12.234341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:11.676551Z","time spent":"557.780615ms","remote":"127.0.0.1:51542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-01-29T18:42:12.234975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.677565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-01-29T18:42:12.235059Z","caller":"traceutil/trace.go:171","msg":"trace[692750363] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:513; }","duration":"124.7979ms","start":"2026-01-29T18:42:12.110254Z","end":"2026-01-29T18:42:12.235052Z","steps":["trace[692750363] 'agreement among raft nodes before linearized reading' (duration: 124.656105ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:12.23521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.90308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:42:12.23531Z","caller":"traceutil/trace.go:171","msg":"trace[1138935384] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:513; }","duration":"158.036655ms","start":"2026-01-29T18:42:12.077265Z","end":"2026-01-29T18:42:12.235302Z","steps":["trace[1138935384] 'agreement among raft nodes before linearized reading' (duration: 157.927039ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:12.236533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.362323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:42:12.236566Z","caller":"traceutil/trace.go:171","msg":"trace[245761074] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:513; }","duration":"617.432781ms","start":"2026-01-29T18:42:11.619125Z","end":"2026-01-29T18:42:12.236557Z","steps":["trace[245761074] 'agreement among raft nodes before linearized reading' (duration: 617.381033ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:12.236584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:11.619107Z","time spent":"617.473409ms","remote":"127.0.0.1:51520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T18:42:12.236995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.476513ms","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-01-29T18:42:12.237164Z","caller":"traceutil/trace.go:171","msg":"trace[200356159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:513; }","duration":"684.613088ms","start":"2026-01-29T18:42:11.552472Z","end":"2026-01-29T18:42:12.237085Z","steps":["trace[200356159] 'agreement among raft nodes before linearized reading' (duration: 684.421905ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:12.237285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:11.552452Z","time spent":"684.79595ms","remote":"127.0.0.1:51770","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":"info","ts":"2026-01-29T18:42:14.295467Z","caller":"traceutil/trace.go:171","msg":"trace[1324178666] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"204.339246ms","start":"2026-01-29T18:42:14.091099Z","end":"2026-01-29T18:42:14.295438Z","steps":["trace[1324178666] 'process raft request' (duration: 200.024475ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:14.295794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.317399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-01-29T18:42:14.295819Z","caller":"traceutil/trace.go:171","msg":"trace[49393501] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:551; }","duration":"181.397336ms","start":"2026-01-29T18:42:14.114414Z","end":"2026-01-29T18:42:14.295812Z","steps":["trace[49393501] 'agreement among raft nodes before linearized reading' (duration: 181.28795ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:14.291399Z","caller":"traceutil/trace.go:171","msg":"trace[1120940192] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:565; }","duration":"176.913531ms","start":"2026-01-29T18:42:14.11447Z","end":"2026-01-29T18:42:14.291383Z","steps":["trace[1120940192] 'read index received' (duration: 176.560823ms)","trace[1120940192] 'applied index is now lower than readState.Index' (duration: 352.178µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:42:14.300014Z","caller":"traceutil/trace.go:171","msg":"trace[1908688347] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"175.198459ms","start":"2026-01-29T18:42:14.124797Z","end":"2026-01-29T18:42:14.299995Z","steps":["trace[1908688347] 'process raft request' (duration: 174.775754ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:14.843692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.743175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:42:14.843738Z","caller":"traceutil/trace.go:171","msg":"trace[874915977] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"192.833172ms","start":"2026-01-29T18:42:14.650894Z","end":"2026-01-29T18:42:14.843727Z","steps":["trace[874915977] 'range keys from in-memory index tree' (duration: 192.696927ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:14.84401Z","caller":"traceutil/trace.go:171","msg":"trace[1537561939] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:570; }","duration":"140.406087ms","start":"2026-01-29T18:42:14.703599Z","end":"2026-01-29T18:42:14.844005Z","steps":["trace[1537561939] 'read index received' (duration: 82.301087ms)","trace[1537561939] 'applied index is now lower than readState.Index' (duration: 58.10439ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:42:14.844224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.62299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188486"} {"level":"info","ts":"2026-01-29T18:42:14.844251Z","caller":"traceutil/trace.go:171","msg":"trace[1084555791] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:556; }","duration":"140.672168ms","start":"2026-01-29T18:42:14.703563Z","end":"2026-01-29T18:42:14.844235Z","steps":["trace[1084555791] 'agreement among raft nodes before linearized reading' (duration: 140.461425ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:14.844478Z","caller":"traceutil/trace.go:171","msg":"trace[874428189] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"193.434571ms","start":"2026-01-29T18:42:14.651037Z","end":"2026-01-29T18:42:14.844472Z","steps":["trace[874428189] 'process raft request' (duration: 134.907806ms)","trace[874428189] 'compare' (duration: 57.995963ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:42:15.038081Z","caller":"traceutil/trace.go:171","msg":"trace[1502004556] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:571; }","duration":"180.759049ms","start":"2026-01-29T18:42:14.857307Z","end":"2026-01-29T18:42:15.038066Z","steps":["trace[1502004556] 'read index received' (duration: 179.935625ms)","trace[1502004556] 'applied index is now lower than readState.Index' (duration: 822.714µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:42:15.038162Z","caller":"traceutil/trace.go:171","msg":"trace[1231521005] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"182.272977ms","start":"2026-01-29T18:42:14.855884Z","end":"2026-01-29T18:42:15.038157Z","steps":["trace[1231521005] 'process raft request' (duration: 181.556029ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:15.038834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.514156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:42:15.03888Z","caller":"traceutil/trace.go:171","msg":"trace[919721261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:557; }","duration":"181.565534ms","start":"2026-01-29T18:42:14.857288Z","end":"2026-01-29T18:42:15.038854Z","steps":["trace[919721261] 'agreement among raft nodes before linearized reading' (duration: 181.499566ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:16.754965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.132514ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T18:42:16.755163Z","caller":"traceutil/trace.go:171","msg":"trace[691543008] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:576; }","duration":"134.504266ms","start":"2026-01-29T18:42:16.620641Z","end":"2026-01-29T18:42:16.755146Z","steps":["trace[691543008] 'read index received' (duration: 12.617622ms)","trace[691543008] 'applied index is now lower than readState.Index' (duration: 121.885394ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:42:16.755302Z","caller":"traceutil/trace.go:171","msg":"trace[26102561] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"156.558557ms","start":"2026-01-29T18:42:16.598723Z","end":"2026-01-29T18:42:16.755282Z","steps":["trace[26102561] 'process raft request' (duration: 34.581965ms)","trace[26102561] 'compare' (duration: 120.992208ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:42:16.755329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.680422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:42:16.75541Z","caller":"traceutil/trace.go:171","msg":"trace[1413610915] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:561; }","duration":"134.796798ms","start":"2026-01-29T18:42:16.620602Z","end":"2026-01-29T18:42:16.755398Z","steps":["trace[1413610915] 'agreement among raft nodes before linearized reading' (duration: 134.686801ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:19.019691Z","caller":"traceutil/trace.go:171","msg":"trace[1414300904] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"152.077512ms","start":"2026-01-29T18:42:18.8676Z","end":"2026-01-29T18:42:19.019678Z","steps":["trace[1414300904] 'process raft request' (duration: 150.678487ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:21.110034Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763060229253870,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T18:42:21.470677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:20.560816Z","time spent":"909.85768ms","remote":"127.0.0.1:51528","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-01-29T18:42:22.624854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00026742s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-29T18:42:22.624916Z","caller":"traceutil/trace.go:171","msg":"trace[161682403] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000362239s","start":"2026-01-29T18:42:20.624541Z","end":"2026-01-29T18:42:22.624903Z","steps":["trace[161682403] 'agreement among raft nodes before linearized reading' (duration: 2.00027924s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:22.624947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:20.62453Z","time spent":"2.000410739s","remote":"127.0.0.1:51520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T18:42:22.655845Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.185352408s","expected-duration":"1s"} {"level":"warn","ts":"2026-01-29T18:42:23.125095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.920367ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T18:42:23.125166Z","caller":"traceutil/trace.go:171","msg":"trace[1170552001] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:588; }","duration":"2.516325107s","start":"2026-01-29T18:42:20.608829Z","end":"2026-01-29T18:42:23.125154Z","steps":["trace[1170552001] 'read index received' (duration: 861.802872ms)","trace[1170552001] 'applied index is now lower than readState.Index' (duration: 1.654521415s)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:42:23.125217Z","caller":"traceutil/trace.go:171","msg":"trace[1509027162] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"2.557127865s","start":"2026-01-29T18:42:20.568068Z","end":"2026-01-29T18:42:23.125195Z","steps":["trace[1509027162] 'process raft request' (duration: 2.088046128s)","trace[1509027162] 'compare' (duration: 468.756268ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:42:23.125283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.516453096s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-29T18:42:23.125307Z","caller":"traceutil/trace.go:171","msg":"trace[1218944877] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:573; }","duration":"2.516497495s","start":"2026-01-29T18:42:20.608802Z","end":"2026-01-29T18:42:23.1253Z","steps":["trace[1218944877] 'agreement among raft nodes before linearized reading' (duration: 2.516403066s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:23.12533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:20.608788Z","time spent":"2.516537275s","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-29T18:42:23.125331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:20.568056Z","time spent":"2.557218734s","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T18:42:23.180638Z","caller":"traceutil/trace.go:171","msg":"trace[910475115] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"1.708994121s","start":"2026-01-29T18:42:21.471617Z","end":"2026-01-29T18:42:23.180612Z","steps":["trace[910475115] 'process raft request' (duration: 1.708851982s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:23.180804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:21.471598Z","time spent":"1.70913028s","remote":"127.0.0.1:51528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T18:42:23.18129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.416312837s","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-01-29T18:42:23.181337Z","caller":"traceutil/trace.go:171","msg":"trace[1027483040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:574; }","duration":"2.416357806s","start":"2026-01-29T18:42:20.764967Z","end":"2026-01-29T18:42:23.181325Z","steps":["trace[1027483040] 'agreement among raft nodes before linearized reading' (duration: 2.416203278s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:23.181377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:20.764925Z","time spent":"2.416443456s","remote":"127.0.0.1:51770","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":"warn","ts":"2026-01-29T18:42:23.181541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.353538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:42:23.181576Z","caller":"traceutil/trace.go:171","msg":"trace[1739033111] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:574; }","duration":"555.402357ms","start":"2026-01-29T18:42:22.626165Z","end":"2026-01-29T18:42:23.181567Z","steps":["trace[1739033111] 'agreement among raft nodes before linearized reading' (duration: 555.352228ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:23.181602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:22.626155Z","time spent":"555.442567ms","remote":"127.0.0.1:51516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T18:42:23.18177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.071761277s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-29T18:42:23.181806Z","caller":"traceutil/trace.go:171","msg":"trace[943112269] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:574; }","duration":"2.071825906s","start":"2026-01-29T18:42:21.109971Z","end":"2026-01-29T18:42:23.181797Z","steps":["trace[943112269] 'agreement among raft nodes before linearized reading' (duration: 2.071738006s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:23.18184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:21.109952Z","time spent":"2.071881806s","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-29T18:42:23.184026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.398135087s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6cjh7\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-01-29T18:42:23.184089Z","caller":"traceutil/trace.go:171","msg":"trace[823857570] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6cjh7; range_end:; response_count:1; response_revision:574; }","duration":"2.398200436s","start":"2026-01-29T18:42:20.785876Z","end":"2026-01-29T18:42:23.184076Z","steps":["trace[823857570] 'agreement among raft nodes before linearized reading' (duration: 2.397969267s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:23.184129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:20.785819Z","time spent":"2.398302885s","remote":"127.0.0.1:51688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16679,"request content":"key:\"/registry/pods/kube-system/cilium-6cjh7\" "} {"level":"warn","ts":"2026-01-29T18:42:23.184235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.16139651s","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-01-29T18:42:23.184267Z","caller":"traceutil/trace.go:171","msg":"trace[1384336545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:574; }","duration":"2.161447039s","start":"2026-01-29T18:42:21.022812Z","end":"2026-01-29T18:42:23.184259Z","steps":["trace[1384336545] 'agreement among raft nodes before linearized reading' (duration: 2.161361419s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:23.184293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:21.022801Z","time spent":"2.161486658s","remote":"127.0.0.1:51770","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-01-29T18:42:23.184524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.367660955s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-29T18:42:23.184562Z","caller":"traceutil/trace.go:171","msg":"trace[1419843018] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:574; }","duration":"2.367720134s","start":"2026-01-29T18:42:20.816831Z","end":"2026-01-29T18:42:23.184552Z","steps":["trace[1419843018] 'agreement among raft nodes before linearized reading' (duration: 2.367613285s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:23.184596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:20.816816Z","time spent":"2.367772614s","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-01-29T18:42:27.979021Z","caller":"traceutil/trace.go:171","msg":"trace[249490941] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"138.501512ms","start":"2026-01-29T18:42:27.840484Z","end":"2026-01-29T18:42:27.978986Z","steps":["trace[249490941] 'process raft request' (duration: 132.884495ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:28.728593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.932598ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-29T18:42:28.728664Z","caller":"traceutil/trace.go:171","msg":"trace[2008243323] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"551.448823ms","start":"2026-01-29T18:42:28.177203Z","end":"2026-01-29T18:42:28.728652Z","steps":["trace[2008243323] 'process raft request' (duration: 23.413204ms)","trace[2008243323] 'compare' (duration: 527.833527ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:42:28.728694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:28.177187Z","time spent":"551.493563ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-29T18:42:51.541487Z","caller":"traceutil/trace.go:171","msg":"trace[415467714] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"120.632687ms","start":"2026-01-29T18:42:51.420837Z","end":"2026-01-29T18:42:51.54147Z","steps":["trace[415467714] 'process raft request' (duration: 120.381597ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:51.667312Z","caller":"traceutil/trace.go:171","msg":"trace[846650119] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"113.120592ms","start":"2026-01-29T18:42:51.554167Z","end":"2026-01-29T18:42:51.667288Z","steps":["trace[846650119] 'process raft request' (duration: 113.07716ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:51.668265Z","caller":"traceutil/trace.go:171","msg":"trace[1655701846] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"121.729903ms","start":"2026-01-29T18:42:51.546477Z","end":"2026-01-29T18:42:51.668207Z","steps":["trace[1655701846] 'process raft request' (duration: 56.691961ms)","trace[1655701846] 'compare' (duration: 63.709076ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:42:51.668434Z","caller":"traceutil/trace.go:171","msg":"trace[360018696] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"115.601205ms","start":"2026-01-29T18:42:51.552823Z","end":"2026-01-29T18:42:51.668425Z","steps":["trace[360018696] 'process raft request' (duration: 114.364474ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:07.634448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.659972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T18:43:07.634527Z","caller":"traceutil/trace.go:171","msg":"trace[85684073] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:815; }","duration":"108.78371ms","start":"2026-01-29T18:43:07.525725Z","end":"2026-01-29T18:43:07.634508Z","steps":["trace[85684073] 'count revisions from in-memory index tree' (duration: 108.569628ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:07.935052Z","caller":"traceutil/trace.go:171","msg":"trace[945259216] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"179.80826ms","start":"2026-01-29T18:43:07.755225Z","end":"2026-01-29T18:43:07.935033Z","steps":["trace[945259216] 'process raft request' (duration: 179.4735ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:12.209918Z","caller":"traceutil/trace.go:171","msg":"trace[2076946481] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"203.601436ms","start":"2026-01-29T18:43:12.006297Z","end":"2026-01-29T18:43:12.209899Z","steps":["trace[2076946481] 'process raft request' (duration: 203.49365ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:12.210318Z","caller":"traceutil/trace.go:171","msg":"trace[1298811311] linearizableReadLoop","detail":"{readStateIndex:857; appliedIndex:857; }","duration":"138.999858ms","start":"2026-01-29T18:43:12.071308Z","end":"2026-01-29T18:43:12.210308Z","steps":["trace[1298811311] 'read index received' (duration: 138.428645ms)","trace[1298811311] 'applied index is now lower than readState.Index' (duration: 570.023µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:43:12.210447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.124646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-29T18:43:12.210473Z","caller":"traceutil/trace.go:171","msg":"trace[195535286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:828; }","duration":"139.206421ms","start":"2026-01-29T18:43:12.071258Z","end":"2026-01-29T18:43:12.210465Z","steps":["trace[195535286] 'agreement among raft nodes before linearized reading' (duration: 139.107065ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:12.210706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.190393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-01-29T18:43:12.210728Z","caller":"traceutil/trace.go:171","msg":"trace[124370888] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:828; }","duration":"138.231845ms","start":"2026-01-29T18:43:12.07249Z","end":"2026-01-29T18:43:12.210722Z","steps":["trace[124370888] 'agreement among raft nodes before linearized reading' (duration: 138.14553ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:46.619197Z","caller":"traceutil/trace.go:171","msg":"trace[1852169526] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"245.59127ms","start":"2026-01-29T18:43:46.373591Z","end":"2026-01-29T18:43:46.619182Z","steps":["trace[1852169526] 'read index received' (duration: 245.448811ms)","trace[1852169526] 'applied index is now lower than readState.Index' (duration: 141.939µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:43:46.619617Z","caller":"traceutil/trace.go:171","msg":"trace[1926554259] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"398.853988ms","start":"2026-01-29T18:43:46.220753Z","end":"2026-01-29T18:43:46.619607Z","steps":["trace[1926554259] 'process raft request' (duration: 398.32757ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:46.620247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:46.220742Z","time spent":"399.442415ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T18:43:46.620779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.742561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-29T18:43:46.620858Z","caller":"traceutil/trace.go:171","msg":"trace[598398499] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:955; }","duration":"189.86132ms","start":"2026-01-29T18:43:46.430979Z","end":"2026-01-29T18:43:46.62084Z","steps":["trace[598398499] 'agreement among raft nodes before linearized reading' (duration: 189.636101ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:46.621094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.682336ms","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-01-29T18:43:46.621123Z","caller":"traceutil/trace.go:171","msg":"trace[606005106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:955; }","duration":"158.745786ms","start":"2026-01-29T18:43:46.462368Z","end":"2026-01-29T18:43:46.621114Z","steps":["trace[606005106] 'agreement among raft nodes before linearized reading' (duration: 158.661636ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:46.620516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.920834ms","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-01-29T18:43:46.62875Z","caller":"traceutil/trace.go:171","msg":"trace[2089564915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:955; }","duration":"255.177459ms","start":"2026-01-29T18:43:46.373559Z","end":"2026-01-29T18:43:46.628737Z","steps":["trace[2089564915] 'agreement among raft nodes before linearized reading' (duration: 246.891674ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:46.898698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.572725ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T18:43:46.899087Z","caller":"traceutil/trace.go:171","msg":"trace[2048284236] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:991; }","duration":"269.437306ms","start":"2026-01-29T18:43:46.629625Z","end":"2026-01-29T18:43:46.899062Z","steps":["trace[2048284236] 'read index received' (duration: 126.402533ms)","trace[2048284236] 'applied index is now lower than readState.Index' (duration: 143.031423ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:43:46.899211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.542676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:43:46.899256Z","caller":"traceutil/trace.go:171","msg":"trace[293903895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:958; }","duration":"269.636315ms","start":"2026-01-29T18:43:46.629606Z","end":"2026-01-29T18:43:46.899243Z","steps":["trace[293903895] 'agreement among raft nodes before linearized reading' (duration: 269.531406ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:46.899411Z","caller":"traceutil/trace.go:171","msg":"trace[1701244222] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"268.148412ms","start":"2026-01-29T18:43:46.631252Z","end":"2026-01-29T18:43:46.8994Z","steps":["trace[1701244222] 'process raft request' (duration: 267.693735ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:46.899622Z","caller":"traceutil/trace.go:171","msg":"trace[1875405618] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"267.374044ms","start":"2026-01-29T18:43:46.63224Z","end":"2026-01-29T18:43:46.899614Z","steps":["trace[1875405618] 'process raft request' (duration: 266.749498ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:46.899745Z","caller":"traceutil/trace.go:171","msg":"trace[1119933389] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"271.13319ms","start":"2026-01-29T18:43:46.628591Z","end":"2026-01-29T18:43:46.899725Z","steps":["trace[1119933389] 'process raft request' (duration: 127.47389ms)","trace[1119933389] 'compare' (duration: 142.496555ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:43:56.141419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.897565ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:43:56.141548Z","caller":"traceutil/trace.go:171","msg":"trace[1761090346] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:982; }","duration":"292.043036ms","start":"2026-01-29T18:43:55.849486Z","end":"2026-01-29T18:43:56.141529Z","steps":["trace[1761090346] 'range keys from in-memory index tree' (duration: 291.879145ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:56.142538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.203351908s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-29T18:43:56.142585Z","caller":"traceutil/trace.go:171","msg":"trace[824882136] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:982; }","duration":"1.203427979s","start":"2026-01-29T18:43:54.939146Z","end":"2026-01-29T18:43:56.142574Z","steps":["trace[824882136] 'range keys from in-memory index tree' (duration: 1.203207008s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:56.142618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:54.939132Z","time spent":"1.203478459s","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-29T18:43:56.143823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.202105886s","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-01-29T18:43:56.14406Z","caller":"traceutil/trace.go:171","msg":"trace[1072712652] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:982; }","duration":"1.202364227s","start":"2026-01-29T18:43:54.941672Z","end":"2026-01-29T18:43:56.144036Z","steps":["trace[1072712652] 'range keys from in-memory index tree' (duration: 1.201973936s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:56.145132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:54.941664Z","time spent":"1.203448673s","remote":"127.0.0.1:51770","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":"warn","ts":"2026-01-29T18:43:56.145626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.193799779s","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-01-29T18:43:56.145669Z","caller":"traceutil/trace.go:171","msg":"trace[491031182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:982; }","duration":"1.19387552s","start":"2026-01-29T18:43:54.951783Z","end":"2026-01-29T18:43:56.145658Z","steps":["trace[491031182] 'range keys from in-memory index tree' (duration: 1.193689959s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:56.146098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.08120505s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:43:56.146221Z","caller":"traceutil/trace.go:171","msg":"trace[1480483432] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:982; }","duration":"1.081359641s","start":"2026-01-29T18:43:55.06484Z","end":"2026-01-29T18:43:56.1462Z","steps":["trace[1480483432] 'count revisions from in-memory index tree' (duration: 1.08112627s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:56.146335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:55.06482Z","time spent":"1.081500862s","remote":"127.0.0.1:51708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-01-29T18:43:56.147883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.660736ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-01-29T18:43:56.148086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:54.951765Z","time spent":"1.196305692s","remote":"127.0.0.1:51770","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":"info","ts":"2026-01-29T18:43:56.150047Z","caller":"traceutil/trace.go:171","msg":"trace[399352160] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1020; }","duration":"1.018881247s","start":"2026-01-29T18:43:55.13115Z","end":"2026-01-29T18:43:56.150031Z","steps":["trace[399352160] 'read index received' (duration: 475.027489ms)","trace[399352160] 'applied index is now lower than readState.Index' (duration: 543.852278ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:43:56.150025Z","caller":"traceutil/trace.go:171","msg":"trace[1774667364] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"1.071125438s","start":"2026-01-29T18:43:55.078878Z","end":"2026-01-29T18:43:56.150003Z","steps":["trace[1774667364] 'process raft request' (duration: 527.2888ms)","trace[1774667364] 'compare' (duration: 537.710404ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:43:56.150802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:55.078857Z","time spent":"1.071866912s","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T18:43:56.150294Z","caller":"traceutil/trace.go:171","msg":"trace[416082146] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"599.119612ms","start":"2026-01-29T18:43:55.551165Z","end":"2026-01-29T18:43:56.150284Z","steps":["trace[416082146] 'process raft request' (duration: 597.751935ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:56.151769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:55.551146Z","time spent":"600.57289ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T18:43:57.021176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.141015ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-01-29T18:43:57.021653Z","caller":"traceutil/trace.go:171","msg":"trace[1440815013] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1022; }","duration":"871.475241ms","start":"2026-01-29T18:43:56.150159Z","end":"2026-01-29T18:43:57.021635Z","steps":["trace[1440815013] 'read index received' (duration: 106.62µs)","trace[1440815013] 'applied index is now lower than readState.Index' (duration: 871.367501ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:43:57.022481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.679961986s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T18:43:57.022641Z","caller":"traceutil/trace.go:171","msg":"trace[323784586] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:984; }","duration":"1.680166307s","start":"2026-01-29T18:43:55.342465Z","end":"2026-01-29T18:43:57.022631Z","steps":["trace[323784586] 'agreement among raft nodes before linearized reading' (duration: 1.679906906s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:57.022727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:55.34245Z","time spent":"1.680267537s","remote":"127.0.0.1:51986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-01-29T18:43:57.023011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.891858228s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:43:57.023092Z","caller":"traceutil/trace.go:171","msg":"trace[835140048] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:984; }","duration":"1.891922038s","start":"2026-01-29T18:43:55.131146Z","end":"2026-01-29T18:43:57.023068Z","steps":["trace[835140048] 'agreement among raft nodes before linearized reading' (duration: 1.019109518s)","trace[835140048] 'range keys from in-memory index tree' (duration: 872.73521ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:43:57.023199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:55.131104Z","time spent":"1.892087511s","remote":"127.0.0.1:51656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-01-29T18:43:57.02343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.133123608s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:43:57.023516Z","caller":"traceutil/trace.go:171","msg":"trace[2084805062] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:984; }","duration":"1.133240419s","start":"2026-01-29T18:43:55.89024Z","end":"2026-01-29T18:43:57.02348Z","steps":["trace[2084805062] 'agreement among raft nodes before linearized reading' (duration: 1.132100711s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:57.023602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:55.890209Z","time spent":"1.13338153s","remote":"127.0.0.1:42958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-01-29T18:43:57.024076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.323573493s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-29T18:43:57.024565Z","caller":"traceutil/trace.go:171","msg":"trace[424800178] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:984; }","duration":"1.324104487s","start":"2026-01-29T18:43:55.700451Z","end":"2026-01-29T18:43:57.024555Z","steps":["trace[424800178] 'agreement among raft nodes before linearized reading' (duration: 1.321904993s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:57.02467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:55.700433Z","time spent":"1.324188017s","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-29T18:43:57.025295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.408350954s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:43:57.025394Z","caller":"traceutil/trace.go:171","msg":"trace[409577863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:984; }","duration":"1.408471965s","start":"2026-01-29T18:43:55.616914Z","end":"2026-01-29T18:43:57.025386Z","steps":["trace[409577863] 'agreement among raft nodes before linearized reading' (duration: 1.405449926s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:57.025575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:55.616902Z","time spent":"1.408642386s","remote":"127.0.0.1:51520","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-01-29T18:43:57.026108Z","caller":"traceutil/trace.go:171","msg":"trace[1013576192] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"867.670743ms","start":"2026-01-29T18:43:56.158429Z","end":"2026-01-29T18:43:57.0261Z","steps":["trace[1013576192] 'process raft request' (duration: 862.947104ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:57.026274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:56.158414Z","time spent":"867.752354ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T18:43:57.23599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.802603ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T18:43:57.236342Z","caller":"traceutil/trace.go:171","msg":"trace[1718361447] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"203.101802ms","start":"2026-01-29T18:43:57.033224Z","end":"2026-01-29T18:43:57.236326Z","steps":["trace[1718361447] 'process raft request' (duration: 203.071731ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:57.236576Z","caller":"traceutil/trace.go:171","msg":"trace[1751287251] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"1.077482887s","start":"2026-01-29T18:43:56.159082Z","end":"2026-01-29T18:43:57.236565Z","steps":["trace[1751287251] 'process raft request' (duration: 867.012979ms)","trace[1751287251] 'compare' (duration: 209.658393ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:43:57.236686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:56.159065Z","time spent":"1.077539208s","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T18:43:57.236886Z","caller":"traceutil/trace.go:171","msg":"trace[1696507002] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"1.076634103s","start":"2026-01-29T18:43:56.160243Z","end":"2026-01-29T18:43:57.236877Z","steps":["trace[1696507002] 'process raft request' (duration: 1.075839938s)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:57.237199Z","caller":"traceutil/trace.go:171","msg":"trace[2099619398] linearizableReadLoop","detail":"{readStateIndex:1026; appliedIndex:1023; }","duration":"215.374469ms","start":"2026-01-29T18:43:57.021799Z","end":"2026-01-29T18:43:57.237174Z","steps":["trace[2099619398] 'read index received' (duration: 580.764µs)","trace[2099619398] 'applied index is now lower than readState.Index' (duration: 214.792215ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:43:57.237457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.404616ms","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-01-29T18:43:57.237547Z","caller":"traceutil/trace.go:171","msg":"trace[194575235] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:988; }","duration":"515.525967ms","start":"2026-01-29T18:43:56.722012Z","end":"2026-01-29T18:43:57.237538Z","steps":["trace[194575235] 'agreement among raft nodes before linearized reading' (duration: 515.367816ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:57.23761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:56.721996Z","time spent":"515.605797ms","remote":"127.0.0.1:51770","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-01-29T18:43:57.23777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.881551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-01-29T18:43:57.237966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:56.160236Z","time spent":"1.076674374s","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T18:43:57.237987Z","caller":"traceutil/trace.go:171","msg":"trace[1656814892] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:988; }","duration":"798.134692ms","start":"2026-01-29T18:43:56.439844Z","end":"2026-01-29T18:43:57.237978Z","steps":["trace[1656814892] 'agreement among raft nodes before linearized reading' (duration: 797.903161ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:57.238078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:56.439824Z","time spent":"798.243112ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-01-29T18:43:57.238221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.076555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:43:57.238277Z","caller":"traceutil/trace.go:171","msg":"trace[341773549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:988; }","duration":"210.146765ms","start":"2026-01-29T18:43:57.028112Z","end":"2026-01-29T18:43:57.238259Z","steps":["trace[341773549] 'agreement among raft nodes before linearized reading' (duration: 210.068795ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:57.238453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.726374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:43:57.240771Z","caller":"traceutil/trace.go:171","msg":"trace[1834849008] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:988; }","duration":"209.064629ms","start":"2026-01-29T18:43:57.031695Z","end":"2026-01-29T18:43:57.24076Z","steps":["trace[1834849008] 'agreement among raft nodes before linearized reading' (duration: 206.740244ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:57.74798Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763060229254861,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-29T18:43:58.113327Z","caller":"traceutil/trace.go:171","msg":"trace[1174073741] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1027; }","duration":"865.71375ms","start":"2026-01-29T18:43:57.247574Z","end":"2026-01-29T18:43:58.113288Z","steps":["trace[1174073741] 'read index received' (duration: 865.538189ms)","trace[1174073741] 'applied index is now lower than readState.Index' (duration: 174.911µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:43:58.113768Z","caller":"traceutil/trace.go:171","msg":"trace[1436952954] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"870.828083ms","start":"2026-01-29T18:43:57.242931Z","end":"2026-01-29T18:43:58.113759Z","steps":["trace[1436952954] 'process raft request' (duration: 870.254239ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:58.113955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:57.242914Z","time spent":"870.905514ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T18:43:58.114187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.610217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:43:58.114243Z","caller":"traceutil/trace.go:171","msg":"trace[317033734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:989; }","duration":"866.695107ms","start":"2026-01-29T18:43:57.24754Z","end":"2026-01-29T18:43:58.114235Z","steps":["trace[317033734] 'agreement among raft nodes before linearized reading' (duration: 866.622386ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:58.114318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:57.247523Z","time spent":"866.787818ms","remote":"127.0.0.1:51520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T18:43:58.114533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.086876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T18:43:58.114792Z","caller":"traceutil/trace.go:171","msg":"trace[317551554] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:989; }","duration":"354.376747ms","start":"2026-01-29T18:43:57.760402Z","end":"2026-01-29T18:43:58.114779Z","steps":["trace[317551554] 'agreement among raft nodes before linearized reading' (duration: 354.108256ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:58.114901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:57.760379Z","time spent":"354.510019ms","remote":"127.0.0.1:52000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-01-29T18:43:58.559548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.830842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T18:43:58.559844Z","caller":"traceutil/trace.go:171","msg":"trace[235056615] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:989; }","duration":"228.131483ms","start":"2026-01-29T18:43:58.331691Z","end":"2026-01-29T18:43:58.559822Z","steps":["trace[235056615] 'count revisions from in-memory index tree' (duration: 227.779141ms)"],"step_count":1}