{"level":"warn","ts":"2026-02-03T10:28:23.215559Z","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-03T10:28:23.215661Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.60: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.19.213.60:2380","--initial-cluster=instance=https://199.19.213.60:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.60:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.60: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-03T10:28:23.215729Z","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-03T10:28:23.215738Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.60:2380"]} {"level":"info","ts":"2026-02-03T10:28:23.215753Z","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-03T10:28:23.216713Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.60:2379"]} {"level":"info","ts":"2026-02-03T10:28:23.216795Z","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.19.213.60:2380"],"listen-peer-urls":["https://199.19.213.60:2380"],"advertise-client-urls":["https://199.19.213.60:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.60:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.60: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-03T10:28:23.400191Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"172.885829ms"} {"level":"info","ts":"2026-02-03T10:28:23.669072Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3490cdb1ff3fc828","cluster-id":"872e6d60ada2e30c"} {"level":"info","ts":"2026-02-03T10:28:23.669141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 switched to configuration voters=()"} {"level":"info","ts":"2026-02-03T10:28:23.669157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 became follower at term 0"} {"level":"info","ts":"2026-02-03T10:28:23.669169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3490cdb1ff3fc828 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-03T10:28:23.669175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 became follower at term 1"} {"level":"info","ts":"2026-02-03T10:28:23.669224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 switched to configuration voters=(3787753450993862696)"} {"level":"warn","ts":"2026-02-03T10:28:23.841503Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-03T10:28:23.910882Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-03T10:28:24.009067Z","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-03T10:28:24.037411Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3490cdb1ff3fc828","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-03T10:28:24.039296Z","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-03T10:28:24.039412Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3490cdb1ff3fc828","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-03T10:28:24.039579Z","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-03T10:28:24.039651Z","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-03T10:28:24.039687Z","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-03T10:28:24.043531Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.60:2380"} {"level":"info","ts":"2026-02-03T10:28:24.043585Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.60:2380"} {"level":"info","ts":"2026-02-03T10:28:24.044554Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3490cdb1ff3fc828","initial-advertise-peer-urls":["https://199.19.213.60:2380"],"listen-peer-urls":["https://199.19.213.60:2380"],"advertise-client-urls":["https://199.19.213.60:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.60:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-03T10:28:24.044649Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-03T10:28:24.04843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 switched to configuration voters=(3787753450993862696)"} {"level":"info","ts":"2026-02-03T10:28:24.048538Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"872e6d60ada2e30c","local-member-id":"3490cdb1ff3fc828","added-peer-id":"3490cdb1ff3fc828","added-peer-peer-urls":["https://199.19.213.60:2380"]} {"level":"info","ts":"2026-02-03T10:28:24.591368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 is starting a new election at term 1"} {"level":"info","ts":"2026-02-03T10:28:24.59163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-03T10:28:24.59179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 received MsgPreVoteResp from 3490cdb1ff3fc828 at term 1"} {"level":"info","ts":"2026-02-03T10:28:24.591928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 became candidate at term 2"} {"level":"info","ts":"2026-02-03T10:28:24.592074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 received MsgVoteResp from 3490cdb1ff3fc828 at term 2"} {"level":"info","ts":"2026-02-03T10:28:24.592191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 became leader at term 2"} {"level":"info","ts":"2026-02-03T10:28:24.592358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3490cdb1ff3fc828 elected leader 3490cdb1ff3fc828 at term 2"} {"level":"info","ts":"2026-02-03T10:28:24.599041Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T10:28:24.604748Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3490cdb1ff3fc828","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.60:2379]}","request-path":"/0/members/3490cdb1ff3fc828/attributes","cluster-id":"872e6d60ada2e30c","publish-timeout":"7s"} {"level":"info","ts":"2026-02-03T10:28:24.60479Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T10:28:24.606002Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T10:28:24.60654Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"872e6d60ada2e30c","local-member-id":"3490cdb1ff3fc828","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T10:28:24.606705Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T10:28:24.60679Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T10:28:24.608146Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-03T10:28:24.609402Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-03T10:28:24.609425Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-03T10:28:24.611744Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.60:2379"} {"level":"warn","ts":"2026-02-03T10:28:24.791723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.031752ms","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:4"} {"level":"info","ts":"2026-02-03T10:28:24.792402Z","caller":"traceutil/trace.go:171","msg":"trace[1367182996] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"109.747123ms","start":"2026-02-03T10:28:24.682645Z","end":"2026-02-03T10:28:24.792393Z","steps":["trace[1367182996] 'count revisions from in-memory index tree' (duration: 109.009862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:24.792583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.01563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-03T10:28:24.79265Z","caller":"traceutil/trace.go:171","msg":"trace[2139143892] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"110.084702ms","start":"2026-02-03T10:28:24.682559Z","end":"2026-02-03T10:28:24.792644Z","steps":["trace[2139143892] 'range keys from in-memory index tree' (duration: 109.880156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:30.395569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.331354ms","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-03T10:28:30.395638Z","caller":"traceutil/trace.go:171","msg":"trace[653155626] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:306; }","duration":"101.437287ms","start":"2026-02-03T10:28:30.294186Z","end":"2026-02-03T10:28:30.395623Z","steps":["trace[653155626] 'range keys from in-memory index tree' (duration: 101.232361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:30.910249Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14422949480990248099,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-03T10:28:31.028734Z","caller":"traceutil/trace.go:171","msg":"trace[1864797157] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"629.307774ms","start":"2026-02-03T10:28:30.399409Z","end":"2026-02-03T10:28:31.028717Z","steps":["trace[1864797157] 'process raft request' (duration: 629.192851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:31.029059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:30.399392Z","time spent":"629.392596ms","remote":"127.0.0.1:49582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:28:32.027419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.006973ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:28:32.027514Z","caller":"traceutil/trace.go:171","msg":"trace[719340091] linearizableReadLoop","detail":"{readStateIndex:316; appliedIndex:315; }","duration":"1.61733239s","start":"2026-02-03T10:28:30.410158Z","end":"2026-02-03T10:28:32.02749Z","steps":["trace[719340091] 'read index received' (duration: 619.174527ms)","trace[719340091] 'applied index is now lower than readState.Index' (duration: 998.157073ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:28:32.027575Z","caller":"traceutil/trace.go:171","msg":"trace[979067211] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"1.62797197s","start":"2026-02-03T10:28:30.399595Z","end":"2026-02-03T10:28:32.027567Z","steps":["trace[979067211] 'process raft request' (duration: 1.067676359s)","trace[979067211] 'compare' (duration: 559.8985ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:32.027608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:30.399571Z","time spent":"1.628022852s","remote":"127.0.0.1:49524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":199,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-03T10:28:32.027869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.61770504s","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-03T10:28:32.02789Z","caller":"traceutil/trace.go:171","msg":"trace[739896354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:308; }","duration":"1.617729561s","start":"2026-02-03T10:28:30.410154Z","end":"2026-02-03T10:28:32.027884Z","steps":["trace[739896354] 'agreement among raft nodes before linearized reading' (duration: 1.617662859s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:32.027909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:30.410122Z","time spent":"1.617782582s","remote":"127.0.0.1:49582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T10:28:32.028025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.13007896s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3444"} {"level":"info","ts":"2026-02-03T10:28:32.028071Z","caller":"traceutil/trace.go:171","msg":"trace[694495016] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:308; }","duration":"1.130156613s","start":"2026-02-03T10:28:30.897903Z","end":"2026-02-03T10:28:32.028059Z","steps":["trace[694495016] 'agreement among raft nodes before linearized reading' (duration: 1.13005087s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:32.028094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:30.89789Z","time spent":"1.130199613s","remote":"127.0.0.1:49504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3467,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-02-03T10:28:32.028142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.923086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-03T10:28:32.028172Z","caller":"traceutil/trace.go:171","msg":"trace[1132060058] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:308; }","duration":"803.974816ms","start":"2026-02-03T10:28:31.224188Z","end":"2026-02-03T10:28:32.028163Z","steps":["trace[1132060058] 'agreement among raft nodes before linearized reading' (duration: 803.926625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:32.028195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:31.224172Z","time spent":"804.018028ms","remote":"127.0.0.1:49582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-03T10:28:32.028219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.128884068s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:28:32.028237Z","caller":"traceutil/trace.go:171","msg":"trace[69333919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:308; }","duration":"1.128917928s","start":"2026-02-03T10:28:30.899315Z","end":"2026-02-03T10:28:32.028233Z","steps":["trace[69333919] 'agreement among raft nodes before linearized reading' (duration: 1.128884557s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:32.02825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:30.899309Z","time spent":"1.12893847s","remote":"127.0.0.1:49330","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-03T10:28:32.028288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.883584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-03T10:28:32.028308Z","caller":"traceutil/trace.go:171","msg":"trace[723612646] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:308; }","duration":"268.936135ms","start":"2026-02-03T10:28:31.759365Z","end":"2026-02-03T10:28:32.028301Z","steps":["trace[723612646] 'agreement among raft nodes before linearized reading' (duration: 268.903944ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:32.148063Z","caller":"traceutil/trace.go:171","msg":"trace[1045142830] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"105.436326ms","start":"2026-02-03T10:28:32.042592Z","end":"2026-02-03T10:28:32.148029Z","steps":["trace[1045142830] 'process raft request' (duration: 105.075356ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:32.149312Z","caller":"traceutil/trace.go:171","msg":"trace[840212734] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"106.619478ms","start":"2026-02-03T10:28:32.042683Z","end":"2026-02-03T10:28:32.149302Z","steps":["trace[840212734] 'process raft request' (duration: 105.161469ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:32.444025Z","caller":"traceutil/trace.go:171","msg":"trace[109966971] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"114.905104ms","start":"2026-02-03T10:28:32.329098Z","end":"2026-02-03T10:28:32.444003Z","steps":["trace[109966971] 'process raft request' (duration: 109.752223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:34.797976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.880735ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:28:34.798131Z","caller":"traceutil/trace.go:171","msg":"trace[1830923719] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"853.94113ms","start":"2026-02-03T10:28:33.944179Z","end":"2026-02-03T10:28:34.79812Z","steps":["trace[1830923719] 'process raft request' (duration: 324.591063ms)","trace[1830923719] 'compare' (duration: 528.753721ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:34.798204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:33.944156Z","time spent":"854.008462ms","remote":"127.0.0.1:49634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:28:34.798082Z","caller":"traceutil/trace.go:171","msg":"trace[643584325] linearizableReadLoop","detail":"{readStateIndex:356; appliedIndex:355; }","duration":"818.132664ms","start":"2026-02-03T10:28:33.979935Z","end":"2026-02-03T10:28:34.798067Z","steps":["trace[643584325] 'read index received' (duration: 49.918831ms)","trace[643584325] 'applied index is now lower than readState.Index' (duration: 768.212583ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:34.798567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.627017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-03T10:28:34.798599Z","caller":"traceutil/trace.go:171","msg":"trace[511646265] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:348; }","duration":"818.71115ms","start":"2026-02-03T10:28:33.979876Z","end":"2026-02-03T10:28:34.798587Z","steps":["trace[511646265] 'agreement among raft nodes before linearized reading' (duration: 818.647158ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:34.798627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:33.979861Z","time spent":"818.760361ms","remote":"127.0.0.1:49524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-02-03T10:28:34.798945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.793908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7228"} {"level":"info","ts":"2026-02-03T10:28:34.798976Z","caller":"traceutil/trace.go:171","msg":"trace[365381890] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:348; }","duration":"570.851069ms","start":"2026-02-03T10:28:34.228116Z","end":"2026-02-03T10:28:34.798967Z","steps":["trace[365381890] 'agreement among raft nodes before linearized reading' (duration: 570.797858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:34.799003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.228104Z","time spent":"570.892231ms","remote":"127.0.0.1:49504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7251,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-02-03T10:28:34.799152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.664776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-03T10:28:34.799179Z","caller":"traceutil/trace.go:171","msg":"trace[1677628849] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:348; }","duration":"419.720548ms","start":"2026-02-03T10:28:34.379451Z","end":"2026-02-03T10:28:34.799171Z","steps":["trace[1677628849] 'agreement among raft nodes before linearized reading' (duration: 419.677087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:34.799204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.379422Z","time spent":"419.775919ms","remote":"127.0.0.1:49524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-02-03T10:28:34.799349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.602657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-03T10:28:34.799396Z","caller":"traceutil/trace.go:171","msg":"trace[1936665357] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:348; }","duration":"469.68027ms","start":"2026-02-03T10:28:34.329707Z","end":"2026-02-03T10:28:34.799387Z","steps":["trace[1936665357] 'agreement among raft nodes before linearized reading' (duration: 469.615008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:34.799422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.329691Z","time spent":"469.725001ms","remote":"127.0.0.1:49524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-02-03T10:28:34.799553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.003403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-03T10:28:34.799578Z","caller":"traceutil/trace.go:171","msg":"trace[394383465] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:348; }","duration":"520.052604ms","start":"2026-02-03T10:28:34.279518Z","end":"2026-02-03T10:28:34.799571Z","steps":["trace[394383465] 'agreement among raft nodes before linearized reading' (duration: 520.010522ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:34.799603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.279504Z","time spent":"520.092105ms","remote":"127.0.0.1:49524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-02-03T10:28:34.799709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.273403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-02-03T10:28:34.799742Z","caller":"traceutil/trace.go:171","msg":"trace[1501996077] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:348; }","duration":"570.319805ms","start":"2026-02-03T10:28:34.229415Z","end":"2026-02-03T10:28:34.799735Z","steps":["trace[1501996077] 'agreement among raft nodes before linearized reading' (duration: 570.272514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:34.799772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.229408Z","time spent":"570.357496ms","remote":"127.0.0.1:49524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-02-03T10:28:34.799885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.61943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6894"} {"level":"info","ts":"2026-02-03T10:28:34.799919Z","caller":"traceutil/trace.go:171","msg":"trace[468150570] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:348; }","duration":"571.669052ms","start":"2026-02-03T10:28:34.228243Z","end":"2026-02-03T10:28:34.799912Z","steps":["trace[468150570] 'agreement among raft nodes before linearized reading' (duration: 571.618471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:34.799943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.228235Z","time spent":"571.702093ms","remote":"127.0.0.1:49504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6917,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-02-03T10:28:34.800064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.806356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4593"} {"level":"info","ts":"2026-02-03T10:28:34.800088Z","caller":"traceutil/trace.go:171","msg":"trace[275005881] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:348; }","duration":"571.846207ms","start":"2026-02-03T10:28:34.228235Z","end":"2026-02-03T10:28:34.800081Z","steps":["trace[275005881] 'agreement among raft nodes before linearized reading' (duration: 571.801325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:34.800111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.22823Z","time spent":"571.876157ms","remote":"127.0.0.1:49504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4616,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-02-03T10:28:34.800211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.993881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4256"} {"level":"info","ts":"2026-02-03T10:28:34.800284Z","caller":"traceutil/trace.go:171","msg":"trace[757333635] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:348; }","duration":"572.029772ms","start":"2026-02-03T10:28:34.228197Z","end":"2026-02-03T10:28:34.800227Z","steps":["trace[757333635] 'agreement among raft nodes before linearized reading' (duration: 571.992011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:34.800318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.228192Z","time spent":"572.115114ms","remote":"127.0.0.1:49504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4279,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-02-03T10:28:34.80083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.24597ms","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-03T10:28:34.800849Z","caller":"traceutil/trace.go:171","msg":"trace[1521763400] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:348; }","duration":"641.29524ms","start":"2026-02-03T10:28:34.159548Z","end":"2026-02-03T10:28:34.800844Z","steps":["trace[1521763400] 'agreement among raft nodes before linearized reading' (duration: 641.261249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:34.800865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.159534Z","time spent":"641.327312ms","remote":"127.0.0.1:49582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T10:28:34.80095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.826323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5260"} {"level":"info","ts":"2026-02-03T10:28:34.800965Z","caller":"traceutil/trace.go:171","msg":"trace[570831425] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:348; }","duration":"572.856745ms","start":"2026-02-03T10:28:34.228104Z","end":"2026-02-03T10:28:34.800961Z","steps":["trace[570831425] 'agreement among raft nodes before linearized reading' (duration: 572.831574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:34.802619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.304671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-03T10:28:34.802695Z","caller":"traceutil/trace.go:171","msg":"trace[33120220] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:348; }","duration":"773.425564ms","start":"2026-02-03T10:28:34.029251Z","end":"2026-02-03T10:28:34.802677Z","steps":["trace[33120220] 'agreement among raft nodes before linearized reading' (duration: 769.628251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:34.80274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.029236Z","time spent":"773.495317ms","remote":"127.0.0.1:49524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-02-03T10:28:34.803037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.398629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-03T10:28:34.803073Z","caller":"traceutil/trace.go:171","msg":"trace[844646402] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:348; }","duration":"650.455121ms","start":"2026-02-03T10:28:34.152607Z","end":"2026-02-03T10:28:34.803062Z","steps":["trace[844646402] 'agreement among raft nodes before linearized reading' (duration: 650.356708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:34.8031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.152596Z","time spent":"650.497752ms","remote":"127.0.0.1:49582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-03T10:28:34.807846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.746387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-03T10:28:34.807913Z","caller":"traceutil/trace.go:171","msg":"trace[438953121] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:348; }","duration":"676.8334ms","start":"2026-02-03T10:28:34.131071Z","end":"2026-02-03T10:28:34.807905Z","steps":["trace[438953121] 'agreement among raft nodes before linearized reading' (duration: 676.734217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:34.807989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.13106Z","time spent":"676.919572ms","remote":"127.0.0.1:49582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-03T10:28:34.808154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.338531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-03T10:28:34.811561Z","caller":"traceutil/trace.go:171","msg":"trace[880032108] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:348; }","duration":"681.829256ms","start":"2026-02-03T10:28:34.129721Z","end":"2026-02-03T10:28:34.81155Z","steps":["trace[880032108] 'agreement among raft nodes before linearized reading' (duration: 678.403353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:34.811648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.12967Z","time spent":"681.946039ms","remote":"127.0.0.1:49524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-02-03T10:28:34.811819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.779055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-03T10:28:34.811876Z","caller":"traceutil/trace.go:171","msg":"trace[409825840] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:348; }","duration":"732.92901ms","start":"2026-02-03T10:28:34.07894Z","end":"2026-02-03T10:28:34.811869Z","steps":["trace[409825840] 'agreement among raft nodes before linearized reading' (duration: 732.851058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:34.811941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.078923Z","time spent":"733.009882ms","remote":"127.0.0.1:49524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-02-03T10:28:34.810753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.228094Z","time spent":"572.882565ms","remote":"127.0.0.1:49504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5283,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-02-03T10:28:34.811121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.101853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-03T10:28:34.812255Z","caller":"traceutil/trace.go:171","msg":"trace[736188572] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:348; }","duration":"584.254215ms","start":"2026-02-03T10:28:34.227973Z","end":"2026-02-03T10:28:34.812228Z","steps":["trace[736188572] 'agreement among raft nodes before linearized reading' (duration: 582.939359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:34.812304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.227956Z","time spent":"584.338898ms","remote":"127.0.0.1:49688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-02-03T10:28:34.811368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.857113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-03T10:28:34.81267Z","caller":"traceutil/trace.go:171","msg":"trace[2035336707] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:348; }","duration":"633.280602ms","start":"2026-02-03T10:28:34.179379Z","end":"2026-02-03T10:28:34.81266Z","steps":["trace[2035336707] 'agreement among raft nodes before linearized reading' (duration: 631.758961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:34.812752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.179365Z","time spent":"633.376775ms","remote":"127.0.0.1:49524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-02-03T10:28:36.278471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.082453573s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:28:36.279012Z","caller":"traceutil/trace.go:171","msg":"trace[944897234] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"1.432957932s","start":"2026-02-03T10:28:34.846019Z","end":"2026-02-03T10:28:36.278977Z","steps":["trace[944897234] 'process raft request' (duration: 1.432929881s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.279091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.846011Z","time spent":"1.433031304s","remote":"127.0.0.1:49582","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-03T10:28:36.279432Z","caller":"traceutil/trace.go:171","msg":"trace[2093027628] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"1.450161542s","start":"2026-02-03T10:28:34.829257Z","end":"2026-02-03T10:28:36.279419Z","steps":["trace[2093027628] 'process raft request' (duration: 366.682781ms)","trace[2093027628] 'compare' (duration: 1.08089723s)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:36.279495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.829244Z","time spent":"1.450210033s","remote":"127.0.0.1:49582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:28:36.279588Z","caller":"traceutil/trace.go:171","msg":"trace[286562064] linearizableReadLoop","detail":"{readStateIndex:357; appliedIndex:356; }","duration":"1.450251524s","start":"2026-02-03T10:28:34.82933Z","end":"2026-02-03T10:28:36.279581Z","steps":["trace[286562064] 'read index received' (duration: 366.618189ms)","trace[286562064] 'applied index is now lower than readState.Index' (duration: 1.083632445s)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:28:36.279727Z","caller":"traceutil/trace.go:171","msg":"trace[329851204] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"1.447875929s","start":"2026-02-03T10:28:34.831828Z","end":"2026-02-03T10:28:36.279703Z","steps":["trace[329851204] 'process raft request' (duration: 1.446729808s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.279762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.831817Z","time spent":"1.447925021s","remote":"127.0.0.1:49634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:28:36.279906Z","caller":"traceutil/trace.go:171","msg":"trace[925809320] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"1.445084493s","start":"2026-02-03T10:28:34.834816Z","end":"2026-02-03T10:28:36.2799Z","steps":["trace[925809320] 'process raft request' (duration: 1.443781707s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.279936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.83481Z","time spent":"1.445111353s","remote":"127.0.0.1:49586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-03T10:28:36.280075Z","caller":"traceutil/trace.go:171","msg":"trace[1916175084] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"1.444844796s","start":"2026-02-03T10:28:34.835222Z","end":"2026-02-03T10:28:36.280067Z","steps":["trace[1916175084] 'process raft request' (duration: 1.443484689s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.280131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.835213Z","time spent":"1.444880717s","remote":"127.0.0.1:49490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-03T10:28:36.280256Z","caller":"traceutil/trace.go:171","msg":"trace[1535253351] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"1.442600205s","start":"2026-02-03T10:28:34.83765Z","end":"2026-02-03T10:28:36.28025Z","steps":["trace[1535253351] 'process raft request' (duration: 1.441136645s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.280302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.837632Z","time spent":"1.442657316s","remote":"127.0.0.1:49788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3620,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-03T10:28:36.28041Z","caller":"traceutil/trace.go:171","msg":"trace[1130428040] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"1.437592099s","start":"2026-02-03T10:28:34.842811Z","end":"2026-02-03T10:28:36.280403Z","steps":["trace[1130428040] 'process raft request' (duration: 1.436026846s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.280465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.842803Z","time spent":"1.43764251s","remote":"127.0.0.1:49582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:28:36.280604Z","caller":"traceutil/trace.go:171","msg":"trace[476187937] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"1.434613547s","start":"2026-02-03T10:28:34.845983Z","end":"2026-02-03T10:28:36.280597Z","steps":["trace[476187937] 'process raft request' (duration: 1.43290276s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.280637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.845975Z","time spent":"1.434644559s","remote":"127.0.0.1:49576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1247,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:28:36.281133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.451798916s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-02-03T10:28:36.281162Z","caller":"traceutil/trace.go:171","msg":"trace[337215353] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:356; }","duration":"1.451852858s","start":"2026-02-03T10:28:34.829302Z","end":"2026-02-03T10:28:36.281154Z","steps":["trace[337215353] 'agreement among raft nodes before linearized reading' (duration: 1.451775996s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.281306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.829288Z","time spent":"1.452009262s","remote":"127.0.0.1:49524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-02-03T10:28:36.281628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.452262829s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-03T10:28:36.281671Z","caller":"traceutil/trace.go:171","msg":"trace[1556906475] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:356; }","duration":"1.45231183s","start":"2026-02-03T10:28:34.829351Z","end":"2026-02-03T10:28:36.281663Z","steps":["trace[1556906475] 'agreement among raft nodes before linearized reading' (duration: 1.452252178s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.281921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.829349Z","time spent":"1.452562537s","remote":"127.0.0.1:49524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-02-03T10:28:36.282476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.240321948s","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-03T10:28:36.282502Z","caller":"traceutil/trace.go:171","msg":"trace[1330001608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:356; }","duration":"1.24038319s","start":"2026-02-03T10:28:35.042112Z","end":"2026-02-03T10:28:36.282496Z","steps":["trace[1330001608] 'agreement among raft nodes before linearized reading' (duration: 1.240324408s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.282715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:35.042089Z","time spent":"1.240426321s","remote":"127.0.0.1:49582","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-03T10:28:36.282948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.44019021s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3840"} {"level":"info","ts":"2026-02-03T10:28:36.283103Z","caller":"traceutil/trace.go:171","msg":"trace[1856937901] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:356; }","duration":"1.44023129s","start":"2026-02-03T10:28:34.842738Z","end":"2026-02-03T10:28:36.282969Z","steps":["trace[1856937901] 'agreement among raft nodes before linearized reading' (duration: 1.440177139s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.283138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.84273Z","time spent":"1.440398935s","remote":"127.0.0.1:49496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3863,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-03T10:28:36.284492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.445078292s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-03T10:28:36.284776Z","caller":"traceutil/trace.go:171","msg":"trace[362199180] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:356; }","duration":"1.445397211s","start":"2026-02-03T10:28:34.839362Z","end":"2026-02-03T10:28:36.284759Z","steps":["trace[362199180] 'agreement among raft nodes before linearized reading' (duration: 1.445067772s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.284867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.839347Z","time spent":"1.445504454s","remote":"127.0.0.1:49524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-02-03T10:28:36.285128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.445870884s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-03T10:28:36.286228Z","caller":"traceutil/trace.go:171","msg":"trace[502114398] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:356; }","duration":"1.446976205s","start":"2026-02-03T10:28:34.839237Z","end":"2026-02-03T10:28:36.286213Z","steps":["trace[502114398] 'agreement among raft nodes before linearized reading' (duration: 1.445836784s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.286465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.839232Z","time spent":"1.447213001s","remote":"127.0.0.1:49524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-02-03T10:28:36.286783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.447799757s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:1 size:3475"} {"level":"info","ts":"2026-02-03T10:28:36.286894Z","caller":"traceutil/trace.go:171","msg":"trace[31162773] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:1; response_revision:356; }","duration":"1.44791853s","start":"2026-02-03T10:28:34.838961Z","end":"2026-02-03T10:28:36.28688Z","steps":["trace[31162773] 'agreement among raft nodes before linearized reading' (duration: 1.447772566s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.286969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.838958Z","time spent":"1.447999142s","remote":"127.0.0.1:49634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":3498,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"warn","ts":"2026-02-03T10:28:36.287173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.448215048s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-03T10:28:36.287253Z","caller":"traceutil/trace.go:171","msg":"trace[1595337124] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:356; }","duration":"1.448301531s","start":"2026-02-03T10:28:34.838938Z","end":"2026-02-03T10:28:36.28724Z","steps":["trace[1595337124] 'agreement among raft nodes before linearized reading' (duration: 1.448197338s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.287323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.838931Z","time spent":"1.448372802s","remote":"127.0.0.1:49524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-02-03T10:28:36.287601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.449201335s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3840"} {"level":"info","ts":"2026-02-03T10:28:36.287638Z","caller":"traceutil/trace.go:171","msg":"trace[486833324] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:356; }","duration":"1.449246286s","start":"2026-02-03T10:28:34.838385Z","end":"2026-02-03T10:28:36.287632Z","steps":["trace[486833324] 'agreement among raft nodes before linearized reading' (duration: 1.449185575s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.287664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.838377Z","time spent":"1.449281917s","remote":"127.0.0.1:49496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3863,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-03T10:28:36.28775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.453007159s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3840"} {"level":"info","ts":"2026-02-03T10:28:36.287785Z","caller":"traceutil/trace.go:171","msg":"trace[1474233338] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:356; }","duration":"1.453046759s","start":"2026-02-03T10:28:34.834733Z","end":"2026-02-03T10:28:36.28778Z","steps":["trace[1474233338] 'agreement among raft nodes before linearized reading' (duration: 1.452997168s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.287811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.834731Z","time spent":"1.453075661s","remote":"127.0.0.1:49496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3863,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-03T10:28:36.287897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.453164564s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3840"} {"level":"info","ts":"2026-02-03T10:28:36.28793Z","caller":"traceutil/trace.go:171","msg":"trace[1211868280] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:356; }","duration":"1.453202964s","start":"2026-02-03T10:28:34.834722Z","end":"2026-02-03T10:28:36.287925Z","steps":["trace[1211868280] 'agreement among raft nodes before linearized reading' (duration: 1.453155813s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.287956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.83472Z","time spent":"1.453231055s","remote":"127.0.0.1:49496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3863,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-03T10:28:36.288038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.453317137s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3840"} {"level":"info","ts":"2026-02-03T10:28:36.288072Z","caller":"traceutil/trace.go:171","msg":"trace[1278967373] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:356; }","duration":"1.453356368s","start":"2026-02-03T10:28:34.834711Z","end":"2026-02-03T10:28:36.288067Z","steps":["trace[1278967373] 'agreement among raft nodes before linearized reading' (duration: 1.453308457s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.288097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.834708Z","time spent":"1.453384759s","remote":"127.0.0.1:49496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3863,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-03T10:28:36.288182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.453486812s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3840"} {"level":"info","ts":"2026-02-03T10:28:36.288217Z","caller":"traceutil/trace.go:171","msg":"trace[693080159] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:356; }","duration":"1.453534823s","start":"2026-02-03T10:28:34.834678Z","end":"2026-02-03T10:28:36.288212Z","steps":["trace[693080159] 'agreement among raft nodes before linearized reading' (duration: 1.453486022s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.288243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:34.83467Z","time spent":"1.453568264s","remote":"127.0.0.1:49496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3863,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-03T10:28:36.697863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.310717ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:28:36.698306Z","caller":"traceutil/trace.go:171","msg":"trace[1641490620] linearizableReadLoop","detail":"{readStateIndex:367; appliedIndex:364; }","duration":"396.803233ms","start":"2026-02-03T10:28:36.301486Z","end":"2026-02-03T10:28:36.698289Z","steps":["trace[1641490620] 'read index received' (duration: 119.991483ms)","trace[1641490620] 'applied index is now lower than readState.Index' (duration: 276.81054ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:36.698398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.905126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-03T10:28:36.69843Z","caller":"traceutil/trace.go:171","msg":"trace[126839998] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:366; }","duration":"396.957817ms","start":"2026-02-03T10:28:36.301464Z","end":"2026-02-03T10:28:36.698422Z","steps":["trace[126839998] 'agreement among raft nodes before linearized reading' (duration: 396.882775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.698464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:36.301457Z","time spent":"396.999309ms","remote":"127.0.0.1:49524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-02-03T10:28:36.698752Z","caller":"traceutil/trace.go:171","msg":"trace[454584059] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"398.330434ms","start":"2026-02-03T10:28:36.30041Z","end":"2026-02-03T10:28:36.69874Z","steps":["trace[454584059] 'process raft request' (duration: 121.088052ms)","trace[454584059] 'compare' (duration: 275.570247ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:36.698821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:36.300379Z","time spent":"398.409217ms","remote":"127.0.0.1:49758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3669,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:28:36.69894Z","caller":"traceutil/trace.go:171","msg":"trace[1039544257] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"398.357756ms","start":"2026-02-03T10:28:36.300573Z","end":"2026-02-03T10:28:36.698931Z","steps":["trace[1039544257] 'process raft request' (duration: 397.382419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.698981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:36.300565Z","time spent":"398.395786ms","remote":"127.0.0.1:49424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-03T10:28:36.69916Z","caller":"traceutil/trace.go:171","msg":"trace[782160777] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"398.5302ms","start":"2026-02-03T10:28:36.300621Z","end":"2026-02-03T10:28:36.699151Z","steps":["trace[782160777] 'process raft request' (duration: 397.396189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.699218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:36.300617Z","time spent":"398.573842ms","remote":"127.0.0.1:49576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2263,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:28:36.700537Z","caller":"traceutil/trace.go:171","msg":"trace[169505712] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"397.722988ms","start":"2026-02-03T10:28:36.302635Z","end":"2026-02-03T10:28:36.700358Z","steps":["trace[169505712] 'process raft request' (duration: 395.437695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.700832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:36.302628Z","time spent":"398.085208ms","remote":"127.0.0.1:49440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2162,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:28:36.701461Z","caller":"traceutil/trace.go:171","msg":"trace[613292104] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"392.449364ms","start":"2026-02-03T10:28:36.308961Z","end":"2026-02-03T10:28:36.70141Z","steps":["trace[613292104] 'process raft request' (duration: 389.297228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.701696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:36.308956Z","time spent":"392.569827ms","remote":"127.0.0.1:49582","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-03T10:28:36.701502Z","caller":"traceutil/trace.go:171","msg":"trace[446177014] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"397.502362ms","start":"2026-02-03T10:28:36.30398Z","end":"2026-02-03T10:28:36.701482Z","steps":["trace[446177014] 'process raft request' (duration: 394.12566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.703113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:36.303974Z","time spent":"399.091815ms","remote":"127.0.0.1:49798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2125,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-03T10:28:36.704083Z","caller":"traceutil/trace.go:171","msg":"trace[835162697] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"396.517275ms","start":"2026-02-03T10:28:36.307555Z","end":"2026-02-03T10:28:36.704073Z","steps":["trace[835162697] 'process raft request' (duration: 390.578743ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:36.705113Z","caller":"traceutil/trace.go:171","msg":"trace[808194004] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"396.586317ms","start":"2026-02-03T10:28:36.308297Z","end":"2026-02-03T10:28:36.704884Z","steps":["trace[808194004] 'process raft request' (duration: 389.926485ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.705349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:36.308291Z","time spent":"396.930406ms","remote":"127.0.0.1:49496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3998,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:28:36.704796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:36.307547Z","time spent":"397.002738ms","remote":"127.0.0.1:49504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4711,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:28:36.705927Z","caller":"traceutil/trace.go:171","msg":"trace[1167399603] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"398.286193ms","start":"2026-02-03T10:28:36.30759Z","end":"2026-02-03T10:28:36.705876Z","steps":["trace[1167399603] 'process raft request' (duration: 390.576343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.706161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:36.307586Z","time spent":"398.450258ms","remote":"127.0.0.1:49440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-03T10:28:36.706569Z","caller":"traceutil/trace.go:171","msg":"trace[600848416] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"398.814198ms","start":"2026-02-03T10:28:36.307705Z","end":"2026-02-03T10:28:36.706519Z","steps":["trace[600848416] 'process raft request' (duration: 390.494941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:36.706759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:36.3077Z","time spent":"398.936111ms","remote":"127.0.0.1:49424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-03T10:28:36.923097Z","caller":"traceutil/trace.go:171","msg":"trace[218818312] linearizableReadLoop","detail":"{readStateIndex:378; appliedIndex:375; }","duration":"165.958606ms","start":"2026-02-03T10:28:36.757121Z","end":"2026-02-03T10:28:36.923079Z","steps":["trace[218818312] 'read index received' (duration: 148.955112ms)","trace[218818312] 'applied index is now lower than readState.Index' (duration: 17.002654ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:28:36.923102Z","caller":"traceutil/trace.go:171","msg":"trace[2121692601] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"203.147241ms","start":"2026-02-03T10:28:36.719922Z","end":"2026-02-03T10:28:36.923069Z","steps":["trace[2121692601] 'process raft request' (duration: 186.141497ms)","trace[2121692601] 'compare' (duration: 16.735496ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:36.923273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.982518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-02-03T10:28:36.923314Z","caller":"traceutil/trace.go:171","msg":"trace[1596937407] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:370; }","duration":"206.04634ms","start":"2026-02-03T10:28:36.717258Z","end":"2026-02-03T10:28:36.923304Z","steps":["trace[1596937407] 'agreement among raft nodes before linearized reading' (duration: 205.903626ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:36.92373Z","caller":"traceutil/trace.go:171","msg":"trace[1037530887] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"203.320115ms","start":"2026-02-03T10:28:36.720399Z","end":"2026-02-03T10:28:36.923719Z","steps":["trace[1037530887] 'process raft request' (duration: 202.560975ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:36.924039Z","caller":"traceutil/trace.go:171","msg":"trace[1800862205] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"196.865959ms","start":"2026-02-03T10:28:36.727157Z","end":"2026-02-03T10:28:36.924023Z","steps":["trace[1800862205] 'process raft request' (duration: 195.854761ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:36.933557Z","caller":"traceutil/trace.go:171","msg":"trace[1800772102] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"168.141865ms","start":"2026-02-03T10:28:36.765402Z","end":"2026-02-03T10:28:36.933543Z","steps":["trace[1800772102] 'process raft request' (duration: 168.059883ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:37.063745Z","caller":"traceutil/trace.go:171","msg":"trace[1158792230] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"129.382969ms","start":"2026-02-03T10:28:36.934343Z","end":"2026-02-03T10:28:37.063726Z","steps":["trace[1158792230] 'process raft request' (duration: 129.113352ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:37.063893Z","caller":"traceutil/trace.go:171","msg":"trace[163575394] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"129.765309ms","start":"2026-02-03T10:28:36.93412Z","end":"2026-02-03T10:28:37.063885Z","steps":["trace[163575394] 'process raft request' (duration: 100.346747ms)","trace[163575394] 'compare' (duration: 28.840367ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:28:37.064184Z","caller":"traceutil/trace.go:171","msg":"trace[1089326304] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"126.51212ms","start":"2026-02-03T10:28:36.937663Z","end":"2026-02-03T10:28:37.064176Z","steps":["trace[1089326304] 'process raft request' (duration: 125.836502ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:37.0642Z","caller":"traceutil/trace.go:171","msg":"trace[681824157] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"123.305433ms","start":"2026-02-03T10:28:36.940853Z","end":"2026-02-03T10:28:37.064158Z","steps":["trace[681824157] 'process raft request' (duration: 122.741777ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:37.064257Z","caller":"traceutil/trace.go:171","msg":"trace[16286768] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"125.206205ms","start":"2026-02-03T10:28:36.939045Z","end":"2026-02-03T10:28:37.064252Z","steps":["trace[16286768] 'process raft request' (duration: 124.516927ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:37.064382Z","caller":"traceutil/trace.go:171","msg":"trace[154294718] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"123.375475ms","start":"2026-02-03T10:28:36.940996Z","end":"2026-02-03T10:28:37.064372Z","steps":["trace[154294718] 'process raft request' (duration: 122.737497ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:37.064681Z","caller":"traceutil/trace.go:171","msg":"trace[28756820] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"111.335457ms","start":"2026-02-03T10:28:36.953336Z","end":"2026-02-03T10:28:37.064671Z","steps":["trace[28756820] 'process raft request' (duration: 110.445763ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:37.319213Z","caller":"traceutil/trace.go:171","msg":"trace[927655574] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"112.293003ms","start":"2026-02-03T10:28:37.206905Z","end":"2026-02-03T10:28:37.319198Z","steps":["trace[927655574] 'process raft request' (duration: 101.642052ms)","trace[927655574] 'compare' (duration: 10.376713ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:28:38.478312Z","caller":"traceutil/trace.go:171","msg":"trace[1309713590] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"145.648422ms","start":"2026-02-03T10:28:38.332649Z","end":"2026-02-03T10:28:38.478298Z","steps":["trace[1309713590] 'process raft request' (duration: 145.612561ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:38.478623Z","caller":"traceutil/trace.go:171","msg":"trace[473406600] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"147.116502ms","start":"2026-02-03T10:28:38.331499Z","end":"2026-02-03T10:28:38.478615Z","steps":["trace[473406600] 'process raft request' (duration: 136.629155ms)","trace[473406600] 'compare' (duration: 10.067965ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:28:53.053957Z","caller":"traceutil/trace.go:171","msg":"trace[103849497] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:486; }","duration":"128.829543ms","start":"2026-02-03T10:28:52.925112Z","end":"2026-02-03T10:28:53.053941Z","steps":["trace[103849497] 'read index received' (duration: 127.377594ms)","trace[103849497] 'applied index is now lower than readState.Index' (duration: 1.451029ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:53.054052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.922686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:28:53.05407Z","caller":"traceutil/trace.go:171","msg":"trace[1453640766] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:472; }","duration":"128.978838ms","start":"2026-02-03T10:28:52.925085Z","end":"2026-02-03T10:28:53.054064Z","steps":["trace[1453640766] 'agreement among raft nodes before linearized reading' (duration: 128.929686ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:03.015943Z","caller":"traceutil/trace.go:171","msg":"trace[677194647] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"629.751013ms","start":"2026-02-03T10:29:02.386176Z","end":"2026-02-03T10:29:03.015927Z","steps":["trace[677194647] 'process raft request' (duration: 629.657081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:03.016051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:02.386157Z","time spent":"629.849466ms","remote":"127.0.0.1:49398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-03T10:29:03.092636Z","caller":"traceutil/trace.go:171","msg":"trace[686325551] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"507.287964ms","start":"2026-02-03T10:29:02.585327Z","end":"2026-02-03T10:29:03.092615Z","steps":["trace[686325551] 'process raft request' (duration: 506.861042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:03.09278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:02.585305Z","time spent":"507.396067ms","remote":"127.0.0.1:49398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-03T10:29:03.097248Z","caller":"traceutil/trace.go:171","msg":"trace[34173663] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:575; }","duration":"468.575508ms","start":"2026-02-03T10:29:02.628655Z","end":"2026-02-03T10:29:03.097231Z","steps":["trace[34173663] 'read index received' (duration: 387.406334ms)","trace[34173663] 'applied index is now lower than readState.Index' (duration: 81.167894ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:29:03.097476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.781673ms","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-03T10:29:03.097518Z","caller":"traceutil/trace.go:171","msg":"trace[1853674593] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:559; }","duration":"468.895286ms","start":"2026-02-03T10:29:02.628612Z","end":"2026-02-03T10:29:03.097508Z","steps":["trace[1853674593] 'agreement among raft nodes before linearized reading' (duration: 468.702871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:03.097549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:02.628592Z","time spent":"468.949988ms","remote":"127.0.0.1:49582","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-02-03T10:29:03.097874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.22649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-03T10:29:03.097938Z","caller":"traceutil/trace.go:171","msg":"trace[1958845421] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:560; }","duration":"409.327242ms","start":"2026-02-03T10:29:02.688594Z","end":"2026-02-03T10:29:03.097922Z","steps":["trace[1958845421] 'agreement among raft nodes before linearized reading' (duration: 409.204348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:03.097969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.12187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-03T10:29:03.097979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:02.688582Z","time spent":"409.389474ms","remote":"127.0.0.1:49582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-03T10:29:03.098009Z","caller":"traceutil/trace.go:171","msg":"trace[312542024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:560; }","duration":"192.174271ms","start":"2026-02-03T10:29:02.905825Z","end":"2026-02-03T10:29:03.097999Z","steps":["trace[312542024] 'agreement among raft nodes before linearized reading' (duration: 192.101449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:03.098105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.317294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:03.09813Z","caller":"traceutil/trace.go:171","msg":"trace[974073593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:560; }","duration":"196.372245ms","start":"2026-02-03T10:29:02.901749Z","end":"2026-02-03T10:29:03.098121Z","steps":["trace[974073593] 'agreement among raft nodes before linearized reading' (duration: 196.334644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:03.098295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.201519ms","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":"warn","ts":"2026-02-03T10:29:03.09832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.250849ms","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-02-03T10:29:03.09833Z","caller":"traceutil/trace.go:171","msg":"trace[553309664] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:560; }","duration":"314.260271ms","start":"2026-02-03T10:29:02.784058Z","end":"2026-02-03T10:29:03.098318Z","steps":["trace[553309664] 'agreement among raft nodes before linearized reading' (duration: 314.209619ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:03.098359Z","caller":"traceutil/trace.go:171","msg":"trace[152483474] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:560; }","duration":"109.364322ms","start":"2026-02-03T10:29:02.988982Z","end":"2026-02-03T10:29:03.098346Z","steps":["trace[152483474] 'agreement among raft nodes before linearized reading' (duration: 109.312511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:03.098407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.817716ms","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-03T10:29:03.098459Z","caller":"traceutil/trace.go:171","msg":"trace[984217314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:560; }","duration":"398.893138ms","start":"2026-02-03T10:29:02.699557Z","end":"2026-02-03T10:29:03.09845Z","steps":["trace[984217314] 'agreement among raft nodes before linearized reading' (duration: 398.822696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:03.098484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:02.699545Z","time spent":"398.933059ms","remote":"127.0.0.1:49582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T10:29:03.098362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:02.784044Z","time spent":"314.312162ms","remote":"127.0.0.1:49398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-03T10:29:03.449724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.726049ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:29:03.449847Z","caller":"traceutil/trace.go:171","msg":"trace[1100871737] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"310.419226ms","start":"2026-02-03T10:29:03.139404Z","end":"2026-02-03T10:29:03.449823Z","steps":["trace[1100871737] 'process raft request' (duration: 166.488021ms)","trace[1100871737] 'compare' (duration: 143.279187ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:29:03.449938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:03.139398Z","time spent":"310.493218ms","remote":"127.0.0.1:49582","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-02-03T10:29:03.825223Z","caller":"traceutil/trace.go:171","msg":"trace[750956655] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:584; }","duration":"561.909275ms","start":"2026-02-03T10:29:03.263298Z","end":"2026-02-03T10:29:03.825207Z","steps":["trace[750956655] 'read index received' (duration: 42.54079ms)","trace[750956655] 'applied index is now lower than readState.Index' (duration: 519.367735ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:29:03.825384Z","caller":"traceutil/trace.go:171","msg":"trace[1604346131] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"638.915285ms","start":"2026-02-03T10:29:03.186461Z","end":"2026-02-03T10:29:03.825376Z","steps":["trace[1604346131] 'process raft request' (duration: 638.667998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:03.825448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:03.186447Z","time spent":"638.960586ms","remote":"127.0.0.1:49398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-03T10:29:03.825672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.369247ms","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-02-03T10:29:03.8257Z","caller":"traceutil/trace.go:171","msg":"trace[1803180012] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:568; }","duration":"562.434359ms","start":"2026-02-03T10:29:03.263258Z","end":"2026-02-03T10:29:03.825693Z","steps":["trace[1803180012] 'agreement among raft nodes before linearized reading' (duration: 562.321676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:03.825719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:03.26324Z","time spent":"562.47507ms","remote":"127.0.0.1:49582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-03T10:29:03.825902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.521812ms","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-03T10:29:03.825928Z","caller":"traceutil/trace.go:171","msg":"trace[86907126] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:568; }","duration":"441.580303ms","start":"2026-02-03T10:29:03.38434Z","end":"2026-02-03T10:29:03.825921Z","steps":["trace[86907126] 'agreement among raft nodes before linearized reading' (duration: 441.487581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:03.825958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:03.384328Z","time spent":"441.625514ms","remote":"127.0.0.1:49398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5609,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-02-03T10:29:03.826667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.493677ms","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-03T10:29:03.826694Z","caller":"traceutil/trace.go:171","msg":"trace[604643187] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:568; }","duration":"241.654011ms","start":"2026-02-03T10:29:03.585033Z","end":"2026-02-03T10:29:03.826687Z","steps":["trace[604643187] 'agreement among raft nodes before linearized reading' (duration: 241.59019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:04.673269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.265113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:29:04.673411Z","caller":"traceutil/trace.go:171","msg":"trace[2008659077] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:587; }","duration":"735.428758ms","start":"2026-02-03T10:29:03.937956Z","end":"2026-02-03T10:29:04.673385Z","steps":["trace[2008659077] 'read index received' (duration: 264.045681ms)","trace[2008659077] 'applied index is now lower than readState.Index' (duration: 471.382467ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:29:04.673572Z","caller":"traceutil/trace.go:171","msg":"trace[1225142598] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"736.383984ms","start":"2026-02-03T10:29:03.937178Z","end":"2026-02-03T10:29:04.673562Z","steps":["trace[1225142598] 'process raft request' (duration: 264.771451ms)","trace[1225142598] 'compare' (duration: 471.180271ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:29:04.673611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:03.937162Z","time spent":"736.429896ms","remote":"127.0.0.1:49582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:29:04.673788Z","caller":"traceutil/trace.go:171","msg":"trace[1149525628] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"735.907181ms","start":"2026-02-03T10:29:03.937877Z","end":"2026-02-03T10:29:04.673784Z","steps":["trace[1149525628] 'process raft request' (duration: 735.465719ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:04.673821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:03.93785Z","time spent":"735.954412ms","remote":"127.0.0.1:49398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24229,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:29:04.673893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.936462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:04.673911Z","caller":"traceutil/trace.go:171","msg":"trace[2114145055] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:571; }","duration":"735.967333ms","start":"2026-02-03T10:29:03.937939Z","end":"2026-02-03T10:29:04.673906Z","steps":["trace[2114145055] 'agreement among raft nodes before linearized reading' (duration: 735.940862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:04.673927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:03.937923Z","time spent":"736.001704ms","remote":"127.0.0.1:49330","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-03T10:29:04.970005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.549176ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:29:04.970077Z","caller":"traceutil/trace.go:171","msg":"trace[927354917] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:589; }","duration":"296.63801ms","start":"2026-02-03T10:29:04.67343Z","end":"2026-02-03T10:29:04.970068Z","steps":["trace[927354917] 'read index received' (duration: 189.901189ms)","trace[927354917] 'applied index is now lower than readState.Index' (duration: 106.736171ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:29:04.970264Z","caller":"traceutil/trace.go:171","msg":"trace[833589007] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"357.905562ms","start":"2026-02-03T10:29:04.612352Z","end":"2026-02-03T10:29:04.970258Z","steps":["trace[833589007] 'process raft request' (duration: 251.048367ms)","trace[833589007] 'compare' (duration: 106.391942ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:29:04.970296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:04.612332Z","time spent":"357.952542ms","remote":"127.0.0.1:49398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-03T10:29:04.970447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.945271ms","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-03T10:29:04.970467Z","caller":"traceutil/trace.go:171","msg":"trace[1508518078] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:572; }","duration":"585.996062ms","start":"2026-02-03T10:29:04.384465Z","end":"2026-02-03T10:29:04.970461Z","steps":["trace[1508518078] 'agreement among raft nodes before linearized reading' (duration: 585.91324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:04.970482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:04.384453Z","time spent":"586.025593ms","remote":"127.0.0.1:49398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9343,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-03T10:29:04.970548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.885107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:04.970566Z","caller":"traceutil/trace.go:171","msg":"trace[1518399923] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:572; }","duration":"604.917349ms","start":"2026-02-03T10:29:04.365645Z","end":"2026-02-03T10:29:04.970562Z","steps":["trace[1518399923] 'agreement among raft nodes before linearized reading' (duration: 604.889358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:04.970582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:04.36564Z","time spent":"604.939559ms","remote":"127.0.0.1:46538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-02-03T10:29:04.97069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.060592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:04.970707Z","caller":"traceutil/trace.go:171","msg":"trace[946426435] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:572; }","duration":"605.095824ms","start":"2026-02-03T10:29:04.365608Z","end":"2026-02-03T10:29:04.970704Z","steps":["trace[946426435] 'agreement among raft nodes before linearized reading' (duration: 605.070613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:04.97072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:04.365601Z","time spent":"605.115224ms","remote":"127.0.0.1:46538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-02-03T10:29:04.970774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.37262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:04.97079Z","caller":"traceutil/trace.go:171","msg":"trace[1990707569] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:572; }","duration":"605.421652ms","start":"2026-02-03T10:29:04.365365Z","end":"2026-02-03T10:29:04.970787Z","steps":["trace[1990707569] 'agreement among raft nodes before linearized reading' (duration: 605.398151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:04.970802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:04.365344Z","time spent":"605.454653ms","remote":"127.0.0.1:46538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-02-03T10:29:04.97086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.439064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:04.970875Z","caller":"traceutil/trace.go:171","msg":"trace[516549586] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:572; }","duration":"634.464054ms","start":"2026-02-03T10:29:04.336408Z","end":"2026-02-03T10:29:04.970872Z","steps":["trace[516549586] 'agreement among raft nodes before linearized reading' (duration: 634.441554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:04.970888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:04.336406Z","time spent":"634.478765ms","remote":"127.0.0.1:46536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 "} {"level":"warn","ts":"2026-02-03T10:29:04.970951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.544926ms","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-02-03T10:29:04.970967Z","caller":"traceutil/trace.go:171","msg":"trace[339499995] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:572; }","duration":"634.567927ms","start":"2026-02-03T10:29:04.336396Z","end":"2026-02-03T10:29:04.970963Z","steps":["trace[339499995] 'agreement among raft nodes before linearized reading' (duration: 634.545496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:04.970982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:04.336392Z","time spent":"634.587477ms","remote":"127.0.0.1:46536","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-02-03T10:29:04.971038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.65176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:04.971054Z","caller":"traceutil/trace.go:171","msg":"trace[1754205715] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:572; }","duration":"634.68672ms","start":"2026-02-03T10:29:04.336364Z","end":"2026-02-03T10:29:04.971051Z","steps":["trace[1754205715] 'agreement among raft nodes before linearized reading' (duration: 634.662199ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:04.971067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:04.336352Z","time spent":"634.71233ms","remote":"127.0.0.1:46536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 "} {"level":"warn","ts":"2026-02-03T10:29:04.971125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.525059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:04.971143Z","caller":"traceutil/trace.go:171","msg":"trace[426848891] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:572; }","duration":"677.550199ms","start":"2026-02-03T10:29:04.293587Z","end":"2026-02-03T10:29:04.971137Z","steps":["trace[426848891] 'agreement among raft nodes before linearized reading' (duration: 677.523789ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:04.971159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:04.293584Z","time spent":"677.57089ms","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-02-03T10:29:04.971223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.640812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:04.971239Z","caller":"traceutil/trace.go:171","msg":"trace[1274985018] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:572; }","duration":"677.665072ms","start":"2026-02-03T10:29:04.29357Z","end":"2026-02-03T10:29:04.971235Z","steps":["trace[1274985018] 'agreement among raft nodes before linearized reading' (duration: 677.642331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:04.971255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:04.293563Z","time spent":"677.688373ms","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-03T10:29:04.971303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.894319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:04.971318Z","caller":"traceutil/trace.go:171","msg":"trace[1465306938] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:572; }","duration":"677.922349ms","start":"2026-02-03T10:29:04.293393Z","end":"2026-02-03T10:29:04.971315Z","steps":["trace[1465306938] 'agreement among raft nodes before linearized reading' (duration: 677.900369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:04.971332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:04.293372Z","time spent":"677.955371ms","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-02-03T10:29:04.971385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.208174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:04.9714Z","caller":"traceutil/trace.go:171","msg":"trace[440932255] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:572; }","duration":"701.229725ms","start":"2026-02-03T10:29:04.270167Z","end":"2026-02-03T10:29:04.971397Z","steps":["trace[440932255] 'agreement among raft nodes before linearized reading' (duration: 701.208175ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:04.971412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:04.270165Z","time spent":"701.244146ms","remote":"127.0.0.1:46532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-02-03T10:29:04.971475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.339198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:04.97149Z","caller":"traceutil/trace.go:171","msg":"trace[704961283] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:572; }","duration":"701.363579ms","start":"2026-02-03T10:29:04.270123Z","end":"2026-02-03T10:29:04.971487Z","steps":["trace[704961283] 'agreement among raft nodes before linearized reading' (duration: 701.341298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:04.971503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:04.27012Z","time spent":"701.379639ms","remote":"127.0.0.1:46532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 "} {"level":"warn","ts":"2026-02-03T10:29:04.971555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.484192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:04.971572Z","caller":"traceutil/trace.go:171","msg":"trace[1199713837] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:572; }","duration":"701.500782ms","start":"2026-02-03T10:29:04.270067Z","end":"2026-02-03T10:29:04.971568Z","steps":["trace[1199713837] 'agreement among raft nodes before linearized reading' (duration: 701.478702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:04.971587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:04.270039Z","time spent":"701.544934ms","remote":"127.0.0.1:46532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 "} {"level":"warn","ts":"2026-02-03T10:29:04.971641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.933475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:04.971657Z","caller":"traceutil/trace.go:171","msg":"trace[1715194953] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:572; }","duration":"741.955415ms","start":"2026-02-03T10:29:04.229698Z","end":"2026-02-03T10:29:04.971653Z","steps":["trace[1715194953] 'agreement among raft nodes before linearized reading' (duration: 741.933285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:04.971673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:04.229696Z","time spent":"741.973706ms","remote":"127.0.0.1:46524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-02-03T10:29:04.971735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.038199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:04.971751Z","caller":"traceutil/trace.go:171","msg":"trace[965936559] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:572; }","duration":"742.054939ms","start":"2026-02-03T10:29:04.229693Z","end":"2026-02-03T10:29:04.971748Z","steps":["trace[965936559] 'agreement among raft nodes before linearized reading' (duration: 742.032728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:04.971764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:04.229681Z","time spent":"742.079269ms","remote":"127.0.0.1:46524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 "} {"level":"warn","ts":"2026-02-03T10:29:04.971818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.144581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:04.971834Z","caller":"traceutil/trace.go:171","msg":"trace[322700151] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:572; }","duration":"742.177482ms","start":"2026-02-03T10:29:04.229653Z","end":"2026-02-03T10:29:04.97183Z","steps":["trace[322700151] 'agreement among raft nodes before linearized reading' (duration: 742.156612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:04.971849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:04.229642Z","time spent":"742.204453ms","remote":"127.0.0.1:46524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 "} {"level":"warn","ts":"2026-02-03T10:29:04.971912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.962755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:04.97193Z","caller":"traceutil/trace.go:171","msg":"trace[969457886] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:572; }","duration":"756.991946ms","start":"2026-02-03T10:29:04.214934Z","end":"2026-02-03T10:29:04.971925Z","steps":["trace[969457886] 'agreement among raft nodes before linearized reading' (duration: 756.964885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:04.971989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:04.214931Z","time spent":"757.053147ms","remote":"127.0.0.1:46516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-02-03T10:29:04.972052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.120529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:04.972063Z","caller":"traceutil/trace.go:171","msg":"trace[640981540] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:572; }","duration":"757.139959ms","start":"2026-02-03T10:29:04.21492Z","end":"2026-02-03T10:29:04.97206Z","steps":["trace[640981540] 'agreement among raft nodes before linearized reading' (duration: 757.120809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:04.972076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:04.214918Z","time spent":"757.15465ms","remote":"127.0.0.1:46516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-02-03T10:29:04.972125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.213762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:04.972136Z","caller":"traceutil/trace.go:171","msg":"trace[1791659204] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:572; }","duration":"757.226792ms","start":"2026-02-03T10:29:04.214906Z","end":"2026-02-03T10:29:04.972132Z","steps":["trace[1791659204] 'agreement among raft nodes before linearized reading' (duration: 757.208822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:04.972147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:04.214866Z","time spent":"757.278034ms","remote":"127.0.0.1:46516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-02-03T10:29:04.972218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.642432ms","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-02-03T10:29:04.97223Z","caller":"traceutil/trace.go:171","msg":"trace[1564794841] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:572; }","duration":"787.687923ms","start":"2026-02-03T10:29:04.184539Z","end":"2026-02-03T10:29:04.972227Z","steps":["trace[1564794841] 'agreement among raft nodes before linearized reading' (duration: 787.646782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:04.972243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:04.184526Z","time spent":"787.714183ms","remote":"127.0.0.1:49398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13252,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-03T10:29:04.972314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.880159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-03T10:29:04.972331Z","caller":"traceutil/trace.go:171","msg":"trace[2060069772] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:572; }","duration":"970.937891ms","start":"2026-02-03T10:29:04.001389Z","end":"2026-02-03T10:29:04.972327Z","steps":["trace[2060069772] 'agreement among raft nodes before linearized reading' (duration: 970.89642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:04.972342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:04.001375Z","time spent":"970.964691ms","remote":"127.0.0.1:49398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4106,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-02-03T10:29:04.97799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.030089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:04.978043Z","caller":"traceutil/trace.go:171","msg":"trace[506617733] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:573; }","duration":"301.126972ms","start":"2026-02-03T10:29:04.676903Z","end":"2026-02-03T10:29:04.97803Z","steps":["trace[506617733] 'agreement among raft nodes before linearized reading' (duration: 301.009919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:04.978071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:04.676894Z","time spent":"301.171703ms","remote":"127.0.0.1:49338","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-03T10:29:04.978255Z","caller":"traceutil/trace.go:171","msg":"trace[765875198] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"156.980931ms","start":"2026-02-03T10:29:04.821267Z","end":"2026-02-03T10:29:04.978248Z","steps":["trace[765875198] 'process raft request' (duration: 156.517109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:05.44018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.8206ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:29:05.440264Z","caller":"traceutil/trace.go:171","msg":"trace[729424362] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"188.475321ms","start":"2026-02-03T10:29:05.251776Z","end":"2026-02-03T10:29:05.440251Z","steps":["trace[729424362] 'process raft request' (duration: 87.512907ms)","trace[729424362] 'compare' (duration: 100.687127ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:29:05.448586Z","caller":"traceutil/trace.go:171","msg":"trace[1790084539] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"176.862704ms","start":"2026-02-03T10:29:05.271714Z","end":"2026-02-03T10:29:05.448577Z","steps":["trace[1790084539] 'process raft request' (duration: 176.429392ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:05.744711Z","caller":"traceutil/trace.go:171","msg":"trace[113809315] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:596; }","duration":"141.90335ms","start":"2026-02-03T10:29:05.602785Z","end":"2026-02-03T10:29:05.744688Z","steps":["trace[113809315] 'read index received' (duration: 70.302827ms)","trace[113809315] 'applied index is now lower than readState.Index' (duration: 71.598053ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:29:05.744955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.148056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188243"} {"level":"info","ts":"2026-02-03T10:29:05.744989Z","caller":"traceutil/trace.go:171","msg":"trace[436365032] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:579; }","duration":"142.219859ms","start":"2026-02-03T10:29:05.602758Z","end":"2026-02-03T10:29:05.744977Z","steps":["trace[436365032] 'agreement among raft nodes before linearized reading' (duration: 142.009363ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:05.746283Z","caller":"traceutil/trace.go:171","msg":"trace[1516157531] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"151.991385ms","start":"2026-02-03T10:29:05.594275Z","end":"2026-02-03T10:29:05.746266Z","steps":["trace[1516157531] 'process raft request' (duration: 78.803659ms)","trace[1516157531] 'compare' (duration: 71.50496ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:29:05.747609Z","caller":"traceutil/trace.go:171","msg":"trace[726287593] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"139.780982ms","start":"2026-02-03T10:29:05.607816Z","end":"2026-02-03T10:29:05.747597Z","steps":["trace[726287593] 'process raft request' (duration: 139.629968ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:07.001629Z","caller":"traceutil/trace.go:171","msg":"trace[460453386] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"314.325853ms","start":"2026-02-03T10:29:06.687282Z","end":"2026-02-03T10:29:07.001608Z","steps":["trace[460453386] 'read index received' (duration: 314.116458ms)","trace[460453386] 'applied index is now lower than readState.Index' (duration: 208.355µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:29:07.002056Z","caller":"traceutil/trace.go:171","msg":"trace[963382459] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"318.929889ms","start":"2026-02-03T10:29:06.683117Z","end":"2026-02-03T10:29:07.002046Z","steps":["trace[963382459] 'process raft request' (duration: 318.341463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:07.002168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:06.683101Z","time spent":"319.015171ms","remote":"127.0.0.1:49496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4323,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:29:07.002496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.206157ms","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-02-03T10:29:07.002523Z","caller":"traceutil/trace.go:171","msg":"trace[1788858081] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:582; }","duration":"315.259069ms","start":"2026-02-03T10:29:06.687257Z","end":"2026-02-03T10:29:07.002516Z","steps":["trace[1788858081] 'agreement among raft nodes before linearized reading' (duration: 315.150546ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:07.002558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:06.687244Z","time spent":"315.29438ms","remote":"127.0.0.1:49582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-03T10:29:07.002779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.523517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:07.002802Z","caller":"traceutil/trace.go:171","msg":"trace[321479637] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:582; }","duration":"289.558878ms","start":"2026-02-03T10:29:06.713236Z","end":"2026-02-03T10:29:07.002795Z","steps":["trace[321479637] 'agreement among raft nodes before linearized reading' (duration: 289.518307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:07.004319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.134927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:07.004343Z","caller":"traceutil/trace.go:171","msg":"trace[1623047783] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:582; }","duration":"102.191788ms","start":"2026-02-03T10:29:06.902144Z","end":"2026-02-03T10:29:07.004336Z","steps":["trace[1623047783] 'agreement among raft nodes before linearized reading' (duration: 102.144537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:07.004411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.630099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:07.004427Z","caller":"traceutil/trace.go:171","msg":"trace[431776146] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:582; }","duration":"238.67925ms","start":"2026-02-03T10:29:06.765742Z","end":"2026-02-03T10:29:07.004421Z","steps":["trace[431776146] 'agreement among raft nodes before linearized reading' (duration: 238.650789ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:07.004868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.122782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:07.004892Z","caller":"traceutil/trace.go:171","msg":"trace[1527303133] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:582; }","duration":"239.153704ms","start":"2026-02-03T10:29:06.76573Z","end":"2026-02-03T10:29:07.004884Z","steps":["trace[1527303133] 'agreement among raft nodes before linearized reading' (duration: 239.116843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:07.004969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.248656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:07.004985Z","caller":"traceutil/trace.go:171","msg":"trace[2093924472] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:582; }","duration":"239.309778ms","start":"2026-02-03T10:29:06.76567Z","end":"2026-02-03T10:29:07.00498Z","steps":["trace[2093924472] 'agreement among raft nodes before linearized reading' (duration: 239.284207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:07.005081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.080656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:07.005099Z","caller":"traceutil/trace.go:171","msg":"trace[1414429308] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:582; }","duration":"252.105847ms","start":"2026-02-03T10:29:06.752988Z","end":"2026-02-03T10:29:07.005094Z","steps":["trace[1414429308] 'agreement among raft nodes before linearized reading' (duration: 252.076946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:07.005186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.196979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:07.005206Z","caller":"traceutil/trace.go:171","msg":"trace[1292598369] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:582; }","duration":"252.22404ms","start":"2026-02-03T10:29:06.752976Z","end":"2026-02-03T10:29:07.0052Z","steps":["trace[1292598369] 'agreement among raft nodes before linearized reading' (duration: 252.195859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:07.005276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.309092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:07.005292Z","caller":"traceutil/trace.go:171","msg":"trace[1364265549] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:582; }","duration":"252.341983ms","start":"2026-02-03T10:29:06.752946Z","end":"2026-02-03T10:29:07.005288Z","steps":["trace[1364265549] 'agreement among raft nodes before linearized reading' (duration: 252.317262ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:07.00536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.825711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:07.005375Z","caller":"traceutil/trace.go:171","msg":"trace[1038826785] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:582; }","duration":"287.870452ms","start":"2026-02-03T10:29:06.7175Z","end":"2026-02-03T10:29:07.005371Z","steps":["trace[1038826785] 'agreement among raft nodes before linearized reading' (duration: 287.846622ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:07.005441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.149079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:07.005457Z","caller":"traceutil/trace.go:171","msg":"trace[1433814482] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:582; }","duration":"292.165409ms","start":"2026-02-03T10:29:06.713287Z","end":"2026-02-03T10:29:07.005452Z","steps":["trace[1433814482] 'agreement among raft nodes before linearized reading' (duration: 292.141919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:11.89382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.048464ms","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-03T10:29:11.893876Z","caller":"traceutil/trace.go:171","msg":"trace[965108101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:599; }","duration":"417.155857ms","start":"2026-02-03T10:29:11.476706Z","end":"2026-02-03T10:29:11.893862Z","steps":["trace[965108101] 'range keys from in-memory index tree' (duration: 416.977861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:11.893902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:11.47669Z","time spent":"417.206168ms","remote":"127.0.0.1:49582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T10:29:11.893874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.995515ms","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-03T10:29:11.893947Z","caller":"traceutil/trace.go:171","msg":"trace[991108322] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:599; }","duration":"610.123808ms","start":"2026-02-03T10:29:11.283809Z","end":"2026-02-03T10:29:11.893933Z","steps":["trace[991108322] 'range keys from in-memory index tree' (duration: 609.881572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:11.894005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:11.283789Z","time spent":"610.20673ms","remote":"127.0.0.1:49582","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-02-03T10:29:11.894053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.638392ms","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-03T10:29:11.894072Z","caller":"traceutil/trace.go:171","msg":"trace[765057561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:599; }","duration":"127.710253ms","start":"2026-02-03T10:29:11.766356Z","end":"2026-02-03T10:29:11.894067Z","steps":["trace[765057561] 'range keys from in-memory index tree' (duration: 127.545248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:11.894128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.836711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-03T10:29:11.894142Z","caller":"traceutil/trace.go:171","msg":"trace[1253377165] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:599; }","duration":"554.850711ms","start":"2026-02-03T10:29:11.339287Z","end":"2026-02-03T10:29:11.894137Z","steps":["trace[1253377165] 'range keys from in-memory index tree' (duration: 554.699618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:11.894158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:11.339194Z","time spent":"554.958515ms","remote":"127.0.0.1:49582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-03T10:29:12.053478Z","caller":"traceutil/trace.go:171","msg":"trace[1168747906] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:618; }","duration":"149.764094ms","start":"2026-02-03T10:29:11.903696Z","end":"2026-02-03T10:29:12.05346Z","steps":["trace[1168747906] 'read index received' (duration: 53.196521ms)","trace[1168747906] 'applied index is now lower than readState.Index' (duration: 96.566923ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:29:12.053492Z","caller":"traceutil/trace.go:171","msg":"trace[1134958837] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"152.196441ms","start":"2026-02-03T10:29:11.901274Z","end":"2026-02-03T10:29:12.05347Z","steps":["trace[1134958837] 'process raft request' (duration: 152.040456ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:12.05356Z","caller":"traceutil/trace.go:171","msg":"trace[1982845405] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"149.95584ms","start":"2026-02-03T10:29:11.903596Z","end":"2026-02-03T10:29:12.053552Z","steps":["trace[1982845405] 'process raft request' (duration: 149.810236ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:12.053671Z","caller":"traceutil/trace.go:171","msg":"trace[672261771] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"148.642694ms","start":"2026-02-03T10:29:11.905021Z","end":"2026-02-03T10:29:12.053664Z","steps":["trace[672261771] 'process raft request' (duration: 148.416488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:12.053764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.053762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:12.053786Z","caller":"traceutil/trace.go:171","msg":"trace[236013361] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"150.101364ms","start":"2026-02-03T10:29:11.903676Z","end":"2026-02-03T10:29:12.053778Z","steps":["trace[236013361] 'agreement among raft nodes before linearized reading' (duration: 150.034772ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:15.194849Z","caller":"traceutil/trace.go:171","msg":"trace[1400183845] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"127.402605ms","start":"2026-02-03T10:29:15.067397Z","end":"2026-02-03T10:29:15.1948Z","steps":["trace[1400183845] 'process raft request' (duration: 127.269351ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:15.277453Z","caller":"traceutil/trace.go:171","msg":"trace[775916365] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"201.794695ms","start":"2026-02-03T10:29:15.075636Z","end":"2026-02-03T10:29:15.277431Z","steps":["trace[775916365] 'process raft request' (duration: 200.984992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:15.278365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.767857ms","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-02-03T10:29:15.278421Z","caller":"traceutil/trace.go:171","msg":"trace[1646028959] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:619; }","duration":"192.82617ms","start":"2026-02-03T10:29:15.085562Z","end":"2026-02-03T10:29:15.278388Z","steps":["trace[1646028959] 'agreement among raft nodes before linearized reading' (duration: 192.688606ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:15.278511Z","caller":"traceutil/trace.go:171","msg":"trace[167431328] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"191.216705ms","start":"2026-02-03T10:29:15.085578Z","end":"2026-02-03T10:29:15.276795Z","steps":["trace[167431328] 'read index received' (duration: 109.777374ms)","trace[167431328] 'applied index is now lower than readState.Index' (duration: 81.438491ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:29:15.854705Z","caller":"traceutil/trace.go:171","msg":"trace[275883939] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"125.607426ms","start":"2026-02-03T10:29:15.729081Z","end":"2026-02-03T10:29:15.854689Z","steps":["trace[275883939] 'process raft request' (duration: 125.492103ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:16.061523Z","caller":"traceutil/trace.go:171","msg":"trace[755758836] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"112.111398ms","start":"2026-02-03T10:29:15.949392Z","end":"2026-02-03T10:29:16.061503Z","steps":["trace[755758836] 'process raft request' (duration: 111.710377ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:16.477888Z","caller":"traceutil/trace.go:171","msg":"trace[1678381594] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"225.1042ms","start":"2026-02-03T10:29:16.25276Z","end":"2026-02-03T10:29:16.477864Z","steps":["trace[1678381594] 'process raft request' (duration: 224.981376ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:16.625075Z","caller":"traceutil/trace.go:171","msg":"trace[320539928] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"362.033354ms","start":"2026-02-03T10:29:16.263025Z","end":"2026-02-03T10:29:16.625058Z","steps":["trace[320539928] 'process raft request' (duration: 361.89666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:16.625205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:16.263011Z","time spent":"362.134586ms","remote":"127.0.0.1:49504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17651,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:29:17.036415Z","caller":"traceutil/trace.go:171","msg":"trace[1064638889] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"145.681843ms","start":"2026-02-03T10:29:16.890705Z","end":"2026-02-03T10:29:17.036386Z","steps":["trace[1064638889] 'process raft request' (duration: 45.956303ms)","trace[1064638889] 'compare' (duration: 99.559405ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:29:22.27191Z","caller":"traceutil/trace.go:171","msg":"trace[2073632712] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:683; }","duration":"166.611294ms","start":"2026-02-03T10:29:22.105282Z","end":"2026-02-03T10:29:22.271894Z","steps":["trace[2073632712] 'read index received' (duration: 166.4655ms)","trace[2073632712] 'applied index is now lower than readState.Index' (duration: 145.184µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:29:22.272143Z","caller":"traceutil/trace.go:171","msg":"trace[245428783] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"184.206344ms","start":"2026-02-03T10:29:22.087929Z","end":"2026-02-03T10:29:22.272136Z","steps":["trace[245428783] 'process raft request' (duration: 183.863414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:22.27233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.033915ms","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-03T10:29:22.272353Z","caller":"traceutil/trace.go:171","msg":"trace[1844006881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:662; }","duration":"167.089147ms","start":"2026-02-03T10:29:22.105256Z","end":"2026-02-03T10:29:22.272345Z","steps":["trace[1844006881] 'agreement among raft nodes before linearized reading' (duration: 167.001345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:22.272508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.14598ms","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-03T10:29:22.272538Z","caller":"traceutil/trace.go:171","msg":"trace[145532914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:662; }","duration":"163.19988ms","start":"2026-02-03T10:29:22.109333Z","end":"2026-02-03T10:29:22.272533Z","steps":["trace[145532914] 'agreement among raft nodes before linearized reading' (duration: 163.093507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:22.272675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.359153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-03T10:29:22.272692Z","caller":"traceutil/trace.go:171","msg":"trace[1922152094] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:662; }","duration":"160.391705ms","start":"2026-02-03T10:29:22.112295Z","end":"2026-02-03T10:29:22.272687Z","steps":["trace[1922152094] 'agreement among raft nodes before linearized reading' (duration: 160.344643ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:23.353586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.518645ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:29:23.353819Z","caller":"traceutil/trace.go:171","msg":"trace[631843575] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"1.077429096s","start":"2026-02-03T10:29:22.276378Z","end":"2026-02-03T10:29:23.353807Z","steps":["trace[631843575] 'process raft request' (duration: 717.644304ms)","trace[631843575] 'compare' (duration: 359.3588ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:29:23.353857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:22.276361Z","time spent":"1.077478056s","remote":"127.0.0.1:49582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:29:23.354039Z","caller":"traceutil/trace.go:171","msg":"trace[1678469661] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"1.07614468s","start":"2026-02-03T10:29:22.27789Z","end":"2026-02-03T10:29:23.354035Z","steps":["trace[1678469661] 'process raft request' (duration: 1.075757789s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:23.354065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:22.277883Z","time spent":"1.076168171s","remote":"127.0.0.1:49582","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-03T10:29:23.354161Z","caller":"traceutil/trace.go:171","msg":"trace[2096090910] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"1.076231633s","start":"2026-02-03T10:29:22.277924Z","end":"2026-02-03T10:29:23.354155Z","steps":["trace[2096090910] 'process raft request' (duration: 1.0757481s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:23.354183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:22.27792Z","time spent":"1.076250723s","remote":"127.0.0.1:49582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:29:23.875674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.964716ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-03T10:29:23.876378Z","caller":"traceutil/trace.go:171","msg":"trace[493081782] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:684; }","duration":"995.245704ms","start":"2026-02-03T10:29:22.881094Z","end":"2026-02-03T10:29:23.87634Z","steps":["trace[493081782] 'read index received' (duration: 112.938501ms)","trace[493081782] 'applied index is now lower than readState.Index' (duration: 882.305663ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:29:23.877094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.988234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-925cg\" ","response":"range_response_count:1 size:18246"} {"level":"info","ts":"2026-02-03T10:29:23.877133Z","caller":"traceutil/trace.go:171","msg":"trace[1575953462] range","detail":"{range_begin:/registry/pods/kube-system/cilium-925cg; range_end:; response_count:1; response_revision:665; }","duration":"996.047306ms","start":"2026-02-03T10:29:22.881071Z","end":"2026-02-03T10:29:23.877118Z","steps":["trace[1575953462] 'agreement among raft nodes before linearized reading' (duration: 995.534752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:23.877163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:22.881057Z","time spent":"996.098728ms","remote":"127.0.0.1:49504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18269,"request content":"key:\"/registry/pods/kube-system/cilium-925cg\" "} {"level":"warn","ts":"2026-02-03T10:29:23.878692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.27239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:23.878723Z","caller":"traceutil/trace.go:171","msg":"trace[508142169] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:665; }","duration":"949.334482ms","start":"2026-02-03T10:29:22.92938Z","end":"2026-02-03T10:29:23.878715Z","steps":["trace[508142169] 'agreement among raft nodes before linearized reading' (duration: 949.147717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:23.878752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:22.929365Z","time spent":"949.381993ms","remote":"127.0.0.1:49330","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-03T10:29:23.879344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.817814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-03T10:29:23.87938Z","caller":"traceutil/trace.go:171","msg":"trace[1935201281] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:665; }","duration":"414.874425ms","start":"2026-02-03T10:29:23.464491Z","end":"2026-02-03T10:29:23.879365Z","steps":["trace[1935201281] 'agreement among raft nodes before linearized reading' (duration: 414.69385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:23.879406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:23.464476Z","time spent":"414.924907ms","remote":"127.0.0.1:49582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-03T10:29:23.87948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.30399ms","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-02-03T10:29:23.879584Z","caller":"traceutil/trace.go:171","msg":"trace[1505199034] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:665; }","duration":"348.426723ms","start":"2026-02-03T10:29:23.531136Z","end":"2026-02-03T10:29:23.879563Z","steps":["trace[1505199034] 'range keys from in-memory index tree' (duration: 348.269629ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:24.106426Z","caller":"traceutil/trace.go:171","msg":"trace[2130051977] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"216.474154ms","start":"2026-02-03T10:29:23.889928Z","end":"2026-02-03T10:29:24.106402Z","steps":["trace[2130051977] 'process raft request' (duration: 216.304889ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:24.141068Z","caller":"traceutil/trace.go:171","msg":"trace[2134992595] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:688; }","duration":"241.536748ms","start":"2026-02-03T10:29:23.899514Z","end":"2026-02-03T10:29:24.141051Z","steps":["trace[2134992595] 'read index received' (duration: 206.651406ms)","trace[2134992595] 'applied index is now lower than readState.Index' (duration: 34.884522ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:29:24.141199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.647721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:24.14122Z","caller":"traceutil/trace.go:171","msg":"trace[341761156] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:667; }","duration":"241.721452ms","start":"2026-02-03T10:29:23.899493Z","end":"2026-02-03T10:29:24.141214Z","steps":["trace[341761156] 'agreement among raft nodes before linearized reading' (duration: 241.63836ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:24.141415Z","caller":"traceutil/trace.go:171","msg":"trace[143820965] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"250.482332ms","start":"2026-02-03T10:29:23.890927Z","end":"2026-02-03T10:29:24.14141Z","steps":["trace[143820965] 'process raft request' (duration: 250.000618ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:24.388611Z","caller":"traceutil/trace.go:171","msg":"trace[1766497986] linearizableReadLoop","detail":"{readStateIndex:693; appliedIndex:691; }","duration":"111.718368ms","start":"2026-02-03T10:29:24.276879Z","end":"2026-02-03T10:29:24.388597Z","steps":["trace[1766497986] 'read index received' (duration: 19.225675ms)","trace[1766497986] 'applied index is now lower than readState.Index' (duration: 92.492203ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:29:24.388749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.853722ms","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-03T10:29:24.38877Z","caller":"traceutil/trace.go:171","msg":"trace[1499859529] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:670; }","duration":"111.925294ms","start":"2026-02-03T10:29:24.276839Z","end":"2026-02-03T10:29:24.388764Z","steps":["trace[1499859529] 'agreement among raft nodes before linearized reading' (duration: 111.814641ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:24.388972Z","caller":"traceutil/trace.go:171","msg":"trace[1862533096] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"138.020115ms","start":"2026-02-03T10:29:24.250947Z","end":"2026-02-03T10:29:24.388967Z","steps":["trace[1862533096] 'process raft request' (duration: 137.558612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:26.723023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.485722ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:29:26.723099Z","caller":"traceutil/trace.go:171","msg":"trace[715584473] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"248.327793ms","start":"2026-02-03T10:29:26.474758Z","end":"2026-02-03T10:29:26.723086Z","steps":["trace[715584473] 'process raft request' (duration: 107.722108ms)","trace[715584473] 'compare' (duration: 140.356368ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:29:33.96807Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14422949480990248963,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-03T10:29:34.392947Z","caller":"traceutil/trace.go:171","msg":"trace[418379925] linearizableReadLoop","detail":"{readStateIndex:773; appliedIndex:772; }","duration":"925.22388ms","start":"2026-02-03T10:29:33.467699Z","end":"2026-02-03T10:29:34.392923Z","steps":["trace[418379925] 'read index received' (duration: 924.911172ms)","trace[418379925] 'applied index is now lower than readState.Index' (duration: 311.858µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:29:34.393299Z","caller":"traceutil/trace.go:171","msg":"trace[1912868425] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"934.599298ms","start":"2026-02-03T10:29:33.458689Z","end":"2026-02-03T10:29:34.393288Z","steps":["trace[1912868425] 'process raft request' (duration: 934.046913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:34.393398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:33.45868Z","time spent":"934.646549ms","remote":"127.0.0.1:49582","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-02-03T10:29:34.393631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.928139ms","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-03T10:29:34.393661Z","caller":"traceutil/trace.go:171","msg":"trace[1694198660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:747; }","duration":"925.992901ms","start":"2026-02-03T10:29:33.467659Z","end":"2026-02-03T10:29:34.393652Z","steps":["trace[1694198660] 'agreement among raft nodes before linearized reading' (duration: 925.869977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:34.393689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:33.467642Z","time spent":"926.040171ms","remote":"127.0.0.1:49582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-03T10:29:34.393864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.322393ms","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-03T10:29:34.393916Z","caller":"traceutil/trace.go:171","msg":"trace[494863606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:747; }","duration":"925.400175ms","start":"2026-02-03T10:29:33.468502Z","end":"2026-02-03T10:29:34.393902Z","steps":["trace[494863606] 'agreement among raft nodes before linearized reading' (duration: 925.2203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:34.393942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:33.468494Z","time spent":"925.443036ms","remote":"127.0.0.1:49582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T10:29:34.393981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.899618ms","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-02-03T10:29:34.394022Z","caller":"traceutil/trace.go:171","msg":"trace[1186281746] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:747; }","duration":"257.97176ms","start":"2026-02-03T10:29:34.136039Z","end":"2026-02-03T10:29:34.394011Z","steps":["trace[1186281746] 'agreement among raft nodes before linearized reading' (duration: 257.882598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:34.733588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.921231ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:29:34.733684Z","caller":"traceutil/trace.go:171","msg":"trace[698033616] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"335.597776ms","start":"2026-02-03T10:29:34.398068Z","end":"2026-02-03T10:29:34.733666Z","steps":["trace[698033616] 'process raft request' (duration: 160.530121ms)","trace[698033616] 'compare' (duration: 174.798568ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:29:34.733742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:34.398054Z","time spent":"335.661798ms","remote":"127.0.0.1:49582","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-02-03T10:29:34.792067Z","caller":"traceutil/trace.go:171","msg":"trace[753134327] linearizableReadLoop","detail":"{readStateIndex:776; appliedIndex:773; }","duration":"314.11429ms","start":"2026-02-03T10:29:34.477933Z","end":"2026-02-03T10:29:34.792047Z","steps":["trace[753134327] 'read index received' (duration: 80.674647ms)","trace[753134327] 'applied index is now lower than readState.Index' (duration: 233.438953ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:29:34.792099Z","caller":"traceutil/trace.go:171","msg":"trace[1515430085] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"393.934094ms","start":"2026-02-03T10:29:34.398146Z","end":"2026-02-03T10:29:34.79208Z","steps":["trace[1515430085] 'process raft request' (duration: 393.78802ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:34.792194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:34.398126Z","time spent":"394.026067ms","remote":"127.0.0.1:49582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:29:34.792215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.266904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-03T10:29:34.792239Z","caller":"traceutil/trace.go:171","msg":"trace[550117586] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:750; }","duration":"314.342986ms","start":"2026-02-03T10:29:34.477889Z","end":"2026-02-03T10:29:34.792232Z","steps":["trace[550117586] 'agreement among raft nodes before linearized reading' (duration: 314.221523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:34.792264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:34.477869Z","time spent":"314.387498ms","remote":"127.0.0.1:49582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-03T10:29:34.792394Z","caller":"traceutil/trace.go:171","msg":"trace[909342836] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"393.122563ms","start":"2026-02-03T10:29:34.399263Z","end":"2026-02-03T10:29:34.792386Z","steps":["trace[909342836] 'process raft request' (duration: 392.759654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:34.792432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:34.399248Z","time spent":"393.162884ms","remote":"127.0.0.1:49582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:29:35.082934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.43093ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:29:35.083084Z","caller":"traceutil/trace.go:171","msg":"trace[1812888877] linearizableReadLoop","detail":"{readStateIndex:778; appliedIndex:776; }","duration":"183.570559ms","start":"2026-02-03T10:29:34.899501Z","end":"2026-02-03T10:29:35.083071Z","steps":["trace[1812888877] 'read index received' (duration: 79.851935ms)","trace[1812888877] 'applied index is now lower than readState.Index' (duration: 103.716234ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:29:35.083147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.640041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:35.083165Z","caller":"traceutil/trace.go:171","msg":"trace[1780606189] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:752; }","duration":"183.684182ms","start":"2026-02-03T10:29:34.899474Z","end":"2026-02-03T10:29:35.083158Z","steps":["trace[1780606189] 'agreement among raft nodes before linearized reading' (duration: 183.631821ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:35.083365Z","caller":"traceutil/trace.go:171","msg":"trace[651194203] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"356.831687ms","start":"2026-02-03T10:29:34.726526Z","end":"2026-02-03T10:29:35.083358Z","steps":["trace[651194203] 'process raft request' (duration: 121.927764ms)","trace[651194203] 'compare' (duration: 234.312517ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:29:35.083408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:34.72651Z","time spent":"356.871437ms","remote":"127.0.0.1:49424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":815,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-03T10:29:35.083593Z","caller":"traceutil/trace.go:171","msg":"trace[448179080] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"287.712414ms","start":"2026-02-03T10:29:34.795874Z","end":"2026-02-03T10:29:35.083586Z","steps":["trace[448179080] 'process raft request' (duration: 287.147779ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:37.203139Z","caller":"traceutil/trace.go:171","msg":"trace[565657045] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"112.392833ms","start":"2026-02-03T10:29:37.090712Z","end":"2026-02-03T10:29:37.203105Z","steps":["trace[565657045] 'process raft request' (duration: 112.210778ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:40.561816Z","caller":"traceutil/trace.go:171","msg":"trace[635661215] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"135.919543ms","start":"2026-02-03T10:29:40.425878Z","end":"2026-02-03T10:29:40.561797Z","steps":["trace[635661215] 'process raft request' (duration: 135.810891ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:51.188368Z","caller":"traceutil/trace.go:171","msg":"trace[332114366] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"310.022093ms","start":"2026-02-03T10:29:50.878331Z","end":"2026-02-03T10:29:51.188353Z","steps":["trace[332114366] 'read index received' (duration: 309.89304ms)","trace[332114366] 'applied index is now lower than readState.Index' (duration: 128.463µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:29:51.188505Z","caller":"traceutil/trace.go:171","msg":"trace[580206694] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"357.223537ms","start":"2026-02-03T10:29:50.831274Z","end":"2026-02-03T10:29:51.188498Z","steps":["trace[580206694] 'process raft request' (duration: 356.97211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:51.1886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:50.831257Z","time spent":"357.289418ms","remote":"127.0.0.1:49582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:29:51.18886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.475932ms","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-03T10:29:51.188908Z","caller":"traceutil/trace.go:171","msg":"trace[1413844566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:799; }","duration":"308.543763ms","start":"2026-02-03T10:29:50.880352Z","end":"2026-02-03T10:29:51.188896Z","steps":["trace[1413844566] 'agreement among raft nodes before linearized reading' (duration: 308.42329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:51.189241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:50.880343Z","time spent":"308.719118ms","remote":"127.0.0.1:49582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T10:29:51.189536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.102335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:51.189577Z","caller":"traceutil/trace.go:171","msg":"trace[1931522903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:799; }","duration":"288.161546ms","start":"2026-02-03T10:29:50.901403Z","end":"2026-02-03T10:29:51.189564Z","steps":["trace[1931522903] 'agreement among raft nodes before linearized reading' (duration: 288.105345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:51.18888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.546527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-03T10:29:51.190333Z","caller":"traceutil/trace.go:171","msg":"trace[159918863] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:799; }","duration":"312.018675ms","start":"2026-02-03T10:29:50.878305Z","end":"2026-02-03T10:29:51.190324Z","steps":["trace[159918863] 'agreement among raft nodes before linearized reading' (duration: 310.533396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:51.190369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:50.878293Z","time spent":"312.068067ms","remote":"127.0.0.1:49582","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-02-03T10:29:51.297091Z","caller":"traceutil/trace.go:171","msg":"trace[263750591] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"102.518513ms","start":"2026-02-03T10:29:51.194548Z","end":"2026-02-03T10:29:51.297066Z","steps":["trace[263750591] 'process raft request' (duration: 83.81967ms)","trace[263750591] 'compare' (duration: 17.81314ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:29:52.18461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.613373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:52.187148Z","caller":"traceutil/trace.go:171","msg":"trace[2133610670] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:803; }","duration":"284.230213ms","start":"2026-02-03T10:29:51.90289Z","end":"2026-02-03T10:29:52.187121Z","steps":["trace[2133610670] 'range keys from in-memory index tree' (duration: 281.360427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:53.403126Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14422949480990249113,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-03T10:29:53.426862Z","caller":"traceutil/trace.go:171","msg":"trace[1422064212] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"804.522987ms","start":"2026-02-03T10:29:52.621995Z","end":"2026-02-03T10:29:53.426518Z","steps":["trace[1422064212] 'process raft request' (duration: 804.335703ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:53.427362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:52.621984Z","time spent":"804.938879ms","remote":"127.0.0.1:49582","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-02-03T10:29:53.937186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.568299ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-03T10:29:53.937279Z","caller":"traceutil/trace.go:171","msg":"trace[1289072497] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:832; }","duration":"1.035256139s","start":"2026-02-03T10:29:52.902006Z","end":"2026-02-03T10:29:53.937262Z","steps":["trace[1289072497] 'read index received' (duration: 524.361872ms)","trace[1289072497] 'applied index is now lower than readState.Index' (duration: 510.892797ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:29:53.93746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.780306ms","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-02-03T10:29:53.937574Z","caller":"traceutil/trace.go:171","msg":"trace[931006068] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:804; }","duration":"405.905539ms","start":"2026-02-03T10:29:53.53165Z","end":"2026-02-03T10:29:53.937556Z","steps":["trace[931006068] 'range keys from in-memory index tree' (duration: 405.763145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:53.937682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.035627089s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:53.937717Z","caller":"traceutil/trace.go:171","msg":"trace[753719925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:804; }","duration":"1.035706321s","start":"2026-02-03T10:29:52.902Z","end":"2026-02-03T10:29:53.937706Z","steps":["trace[753719925] 'agreement among raft nodes before linearized reading' (duration: 1.035599238s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:53.937752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:52.901957Z","time spent":"1.035784253s","remote":"127.0.0.1:49338","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-03T10:29:53.937856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010949609s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:53.937889Z","caller":"traceutil/trace.go:171","msg":"trace[311837974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:804; }","duration":"1.011008591s","start":"2026-02-03T10:29:52.92687Z","end":"2026-02-03T10:29:53.937879Z","steps":["trace[311837974] 'agreement among raft nodes before linearized reading' (duration: 1.010951559s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:53.93792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:52.926859Z","time spent":"1.011055192s","remote":"127.0.0.1:49330","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-03T10:29:53.938086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.676619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:53.938145Z","caller":"traceutil/trace.go:171","msg":"trace[462684493] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:804; }","duration":"302.763591ms","start":"2026-02-03T10:29:53.635374Z","end":"2026-02-03T10:29:53.938137Z","steps":["trace[462684493] 'agreement among raft nodes before linearized reading' (duration: 302.688349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:53.938173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:53.635357Z","time spent":"302.808762ms","remote":"127.0.0.1:49598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-02-03T10:29:53.938372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.200743ms","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-03T10:29:53.938398Z","caller":"traceutil/trace.go:171","msg":"trace[709048646] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:804; }","duration":"624.248855ms","start":"2026-02-03T10:29:53.314142Z","end":"2026-02-03T10:29:53.93839Z","steps":["trace[709048646] 'agreement among raft nodes before linearized reading' (duration: 624.144612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:53.938423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:53.314107Z","time spent":"624.310037ms","remote":"127.0.0.1:49582","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-03T10:29:53.938594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.553093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-03T10:29:53.938619Z","caller":"traceutil/trace.go:171","msg":"trace[713585460] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:804; }","duration":"635.609834ms","start":"2026-02-03T10:29:53.303002Z","end":"2026-02-03T10:29:53.938611Z","steps":["trace[713585460] 'agreement among raft nodes before linearized reading' (duration: 635.532062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:53.938643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:53.302983Z","time spent":"635.654796ms","remote":"127.0.0.1:49582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-03T10:29:53.938799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.899481ms","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-03T10:29:53.938826Z","caller":"traceutil/trace.go:171","msg":"trace[1528730912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:804; }","duration":"671.956062ms","start":"2026-02-03T10:29:53.26686Z","end":"2026-02-03T10:29:53.938816Z","steps":["trace[1528730912] 'agreement among raft nodes before linearized reading' (duration: 671.88471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:53.938853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:53.266758Z","time spent":"672.090246ms","remote":"127.0.0.1:49582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T10:29:53.939003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.880709ms","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-03T10:29:53.939027Z","caller":"traceutil/trace.go:171","msg":"trace[804564716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:804; }","duration":"743.93146ms","start":"2026-02-03T10:29:53.195088Z","end":"2026-02-03T10:29:53.939019Z","steps":["trace[804564716] 'agreement among raft nodes before linearized reading' (duration: 743.864118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:53.939056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:53.195071Z","time spent":"743.973701ms","remote":"127.0.0.1:49582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-03T10:30:01.947564Z","caller":"traceutil/trace.go:171","msg":"trace[1933551123] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"109.472557ms","start":"2026-02-03T10:30:01.838061Z","end":"2026-02-03T10:30:01.947534Z","steps":["trace[1933551123] 'process raft request' (duration: 109.336183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:30:13.203169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.825328ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-03T10:30:13.699296Z","caller":"traceutil/trace.go:171","msg":"trace[1755400718] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"159.184126ms","start":"2026-02-03T10:30:13.540098Z","end":"2026-02-03T10:30:13.699282Z","steps":["trace[1755400718] 'process raft request' (duration: 158.95796ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:30:16.194978Z","caller":"traceutil/trace.go:171","msg":"trace[1848442504] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"105.407919ms","start":"2026-02-03T10:30:16.089553Z","end":"2026-02-03T10:30:16.194961Z","steps":["trace[1848442504] 'process raft request' (duration: 105.164092ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:30:16.338347Z","caller":"traceutil/trace.go:171","msg":"trace[2098086451] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"138.882451ms","start":"2026-02-03T10:30:16.199442Z","end":"2026-02-03T10:30:16.338324Z","steps":["trace[2098086451] 'process raft request' (duration: 138.795819ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:30:16.339307Z","caller":"traceutil/trace.go:171","msg":"trace[1107395422] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"138.131002ms","start":"2026-02-03T10:30:16.201169Z","end":"2026-02-03T10:30:16.3393Z","steps":["trace[1107395422] 'process raft request' (duration: 138.110841ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:30:16.339397Z","caller":"traceutil/trace.go:171","msg":"trace[1596733591] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"138.268965ms","start":"2026-02-03T10:30:16.201122Z","end":"2026-02-03T10:30:16.339391Z","steps":["trace[1596733591] 'process raft request' (duration: 138.123991ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:30:22.098411Z","caller":"traceutil/trace.go:171","msg":"trace[172223643] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:978; }","duration":"198.317908ms","start":"2026-02-03T10:30:21.900064Z","end":"2026-02-03T10:30:22.098382Z","steps":["trace[172223643] 'read index received' (duration: 198.077191ms)","trace[172223643] 'applied index is now lower than readState.Index' (duration: 239.627µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:30:22.098573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.484843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:30:22.098594Z","caller":"traceutil/trace.go:171","msg":"trace[384032480] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:943; }","duration":"198.593675ms","start":"2026-02-03T10:30:21.899993Z","end":"2026-02-03T10:30:22.098587Z","steps":["trace[384032480] 'agreement among raft nodes before linearized reading' (duration: 198.496073ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:30:22.099037Z","caller":"traceutil/trace.go:171","msg":"trace[1769679575] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"354.239138ms","start":"2026-02-03T10:30:21.744787Z","end":"2026-02-03T10:30:22.099027Z","steps":["trace[1769679575] 'process raft request' (duration: 353.433837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:30:22.099118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:30:21.744774Z","time spent":"354.278889ms","remote":"127.0.0.1:49582","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-02-03T10:30:23.132503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.561133ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-03T10:30:28.979857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.034117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:30:28.979954Z","caller":"traceutil/trace.go:171","msg":"trace[1296136181] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"398.335271ms","start":"2026-02-03T10:30:28.581608Z","end":"2026-02-03T10:30:28.979943Z","steps":["trace[1296136181] 'process raft request' (duration: 276.15873ms)","trace[1296136181] 'compare' (duration: 121.894163ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:30:28.979983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:30:28.581585Z","time spent":"398.386272ms","remote":"127.0.0.1:49424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":762,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-03T10:30:29.760431Z","caller":"traceutil/trace.go:171","msg":"trace[1281945612] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"137.251288ms","start":"2026-02-03T10:30:29.623163Z","end":"2026-02-03T10:30:29.760414Z","steps":["trace[1281945612] 'process raft request' (duration: 136.842578ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:30:30.06445Z","caller":"traceutil/trace.go:171","msg":"trace[475426222] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1012; }","duration":"134.913226ms","start":"2026-02-03T10:30:29.929521Z","end":"2026-02-03T10:30:30.064434Z","steps":["trace[475426222] 'read index received' (duration: 134.773963ms)","trace[475426222] 'applied index is now lower than readState.Index' (duration: 138.633µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:30:30.064554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.018799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:30:30.064576Z","caller":"traceutil/trace.go:171","msg":"trace[1828568427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:974; }","duration":"135.07194ms","start":"2026-02-03T10:30:29.929495Z","end":"2026-02-03T10:30:30.064567Z","steps":["trace[1828568427] 'agreement among raft nodes before linearized reading' (duration: 135.008138ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:30:30.064775Z","caller":"traceutil/trace.go:171","msg":"trace[291097706] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"295.426287ms","start":"2026-02-03T10:30:29.76934Z","end":"2026-02-03T10:30:30.064767Z","steps":["trace[291097706] 'process raft request' (duration: 294.997986ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:30:30.272379Z","caller":"traceutil/trace.go:171","msg":"trace[154560075] linearizableReadLoop","detail":"{readStateIndex:1015; appliedIndex:1014; }","duration":"189.324341ms","start":"2026-02-03T10:30:30.08304Z","end":"2026-02-03T10:30:30.272364Z","steps":["trace[154560075] 'read index received' (duration: 43.676351ms)","trace[154560075] 'applied index is now lower than readState.Index' (duration: 145.64724ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:30:30.272439Z","caller":"traceutil/trace.go:171","msg":"trace[1864062680] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"189.537317ms","start":"2026-02-03T10:30:30.082861Z","end":"2026-02-03T10:30:30.272398Z","steps":["trace[1864062680] 'process raft request' (duration: 174.797508ms)","trace[1864062680] 'compare' (duration: 14.594535ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:30:30.272684Z","caller":"traceutil/trace.go:171","msg":"trace[374491193] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"174.85468ms","start":"2026-02-03T10:30:30.09781Z","end":"2026-02-03T10:30:30.272664Z","steps":["trace[374491193] 'process raft request' (duration: 174.520531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:30:30.272872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.469595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:30:30.272895Z","caller":"traceutil/trace.go:171","msg":"trace[462082946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:977; }","duration":"189.510066ms","start":"2026-02-03T10:30:30.083378Z","end":"2026-02-03T10:30:30.272888Z","steps":["trace[462082946] 'agreement among raft nodes before linearized reading' (duration: 189.458784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:30:30.2729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.847705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-t65xh\" ","response":"range_response_count:1 size:10616"} {"level":"info","ts":"2026-02-03T10:30:30.272957Z","caller":"traceutil/trace.go:171","msg":"trace[337161905] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-t65xh; range_end:; response_count:1; response_revision:977; }","duration":"189.901047ms","start":"2026-02-03T10:30:30.083018Z","end":"2026-02-03T10:30:30.272919Z","steps":["trace[337161905] 'agreement among raft nodes before linearized reading' (duration: 189.801024ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:30:30.273072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.96231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-03T10:30:30.27309Z","caller":"traceutil/trace.go:171","msg":"trace[779493727] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:977; }","duration":"151.002921ms","start":"2026-02-03T10:30:30.122082Z","end":"2026-02-03T10:30:30.273085Z","steps":["trace[779493727] 'agreement among raft nodes before linearized reading' (duration: 150.905048ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:30:30.54188Z","caller":"traceutil/trace.go:171","msg":"trace[427697956] linearizableReadLoop","detail":"{readStateIndex:1017; appliedIndex:1016; }","duration":"258.102744ms","start":"2026-02-03T10:30:30.283759Z","end":"2026-02-03T10:30:30.541862Z","steps":["trace[427697956] 'read index received' (duration: 158.1854ms)","trace[427697956] 'applied index is now lower than readState.Index' (duration: 99.916684ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:30:30.541973Z","caller":"traceutil/trace.go:171","msg":"trace[661732358] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"258.268759ms","start":"2026-02-03T10:30:30.283689Z","end":"2026-02-03T10:30:30.541958Z","steps":["trace[661732358] 'process raft request' (duration: 158.317004ms)","trace[661732358] 'compare' (duration: 99.77358ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:30:30.542111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.34111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-03T10:30:30.542154Z","caller":"traceutil/trace.go:171","msg":"trace[1314462460] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:978; }","duration":"258.408572ms","start":"2026-02-03T10:30:30.283734Z","end":"2026-02-03T10:30:30.542143Z","steps":["trace[1314462460] 'agreement among raft nodes before linearized reading' (duration: 258.33763ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:30:44.096448Z","caller":"traceutil/trace.go:171","msg":"trace[1674565245] linearizableReadLoop","detail":"{readStateIndex:1113; appliedIndex:1112; }","duration":"193.00283ms","start":"2026-02-03T10:30:43.903417Z","end":"2026-02-03T10:30:44.096419Z","steps":["trace[1674565245] 'read index received' (duration: 165.614365ms)","trace[1674565245] 'applied index is now lower than readState.Index' (duration: 27.387325ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:30:44.096695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.228696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:30:44.096738Z","caller":"traceutil/trace.go:171","msg":"trace[1753053223] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1071; }","duration":"193.38838ms","start":"2026-02-03T10:30:43.903339Z","end":"2026-02-03T10:30:44.096727Z","steps":["trace[1753053223] 'agreement among raft nodes before linearized reading' (duration: 193.203385ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:30:44.097408Z","caller":"traceutil/trace.go:171","msg":"trace[1609237320] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"236.145983ms","start":"2026-02-03T10:30:43.861246Z","end":"2026-02-03T10:30:44.097392Z","steps":["trace[1609237320] 'process raft request' (duration: 194.181821ms)","trace[1609237320] 'compare' (duration: 40.844712ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:33:52.947228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.528083ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-03T10:34:07.941891Z","caller":"traceutil/trace.go:171","msg":"trace[865719638] transaction","detail":"{read_only:false; response_revision:2044; number_of_response:1; }","duration":"104.168767ms","start":"2026-02-03T10:34:07.837703Z","end":"2026-02-03T10:34:07.941872Z","steps":["trace[865719638] 'process raft request' (duration: 77.694869ms)","trace[865719638] 'compare' (duration: 26.219831ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:34:07.94206Z","caller":"traceutil/trace.go:171","msg":"trace[366683163] transaction","detail":"{read_only:false; response_revision:2045; number_of_response:1; }","duration":"104.235218ms","start":"2026-02-03T10:34:07.837818Z","end":"2026-02-03T10:34:07.942053Z","steps":["trace[366683163] 'process raft request' (duration: 103.891118ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:34:14.091648Z","caller":"traceutil/trace.go:171","msg":"trace[1401440750] transaction","detail":"{read_only:false; response_revision:2070; number_of_response:1; }","duration":"117.035116ms","start":"2026-02-03T10:34:13.97459Z","end":"2026-02-03T10:34:14.091626Z","steps":["trace[1401440750] 'process raft request' (duration: 116.900582ms)"],"step_count":1}