{"level":"warn","ts":"2026-02-10T15:01:32.147624Z","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-10T15:01:32.147734Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.192: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.192:2380","--initial-cluster=instance=https://199.19.213.192:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.192:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.192: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-10T15:01:32.147983Z","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-10T15:01:32.147995Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.192:2380"]} {"level":"info","ts":"2026-02-10T15:01:32.148013Z","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-10T15:01:32.162359Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.192:2379"]} {"level":"info","ts":"2026-02-10T15:01:32.162477Z","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.192:2380"],"listen-peer-urls":["https://199.19.213.192:2380"],"advertise-client-urls":["https://199.19.213.192:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.192:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.192: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-10T15:01:32.168119Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.449359ms"} {"level":"info","ts":"2026-02-10T15:01:32.191341Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"341a3703c39c1f5b","cluster-id":"65341987fa712ba2"} {"level":"info","ts":"2026-02-10T15:01:32.191402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b switched to configuration voters=()"} {"level":"info","ts":"2026-02-10T15:01:32.191479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b became follower at term 0"} {"level":"info","ts":"2026-02-10T15:01:32.191508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 341a3703c39c1f5b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-10T15:01:32.191541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b became follower at term 1"} {"level":"info","ts":"2026-02-10T15:01:32.191617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b switched to configuration voters=(3754373728672948059)"} {"level":"warn","ts":"2026-02-10T15:01:32.207495Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-10T15:01:32.209388Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-10T15:01:32.210546Z","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-10T15:01:32.212615Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"341a3703c39c1f5b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-10T15:01:32.214216Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"341a3703c39c1f5b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-10T15:01:32.216956Z","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-10T15:01:32.216998Z","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-10T15:01:32.217007Z","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-10T15:01:32.217279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b switched to configuration voters=(3754373728672948059)"} {"level":"info","ts":"2026-02-10T15:01:32.217401Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65341987fa712ba2","local-member-id":"341a3703c39c1f5b","added-peer-id":"341a3703c39c1f5b","added-peer-peer-urls":["https://199.19.213.192:2380"]} {"level":"info","ts":"2026-02-10T15:01:32.229439Z","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-10T15:01:32.229768Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.192:2380"} {"level":"info","ts":"2026-02-10T15:01:32.229783Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.192:2380"} {"level":"info","ts":"2026-02-10T15:01:32.232786Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"341a3703c39c1f5b","initial-advertise-peer-urls":["https://199.19.213.192:2380"],"listen-peer-urls":["https://199.19.213.192:2380"],"advertise-client-urls":["https://199.19.213.192:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.192:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-10T15:01:32.232809Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-10T15:01:32.392635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b is starting a new election at term 1"} {"level":"info","ts":"2026-02-10T15:01:32.392742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b became pre-candidate at term 1"} {"level":"info","ts":"2026-02-10T15:01:32.392813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b received MsgPreVoteResp from 341a3703c39c1f5b at term 1"} {"level":"info","ts":"2026-02-10T15:01:32.392879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b became candidate at term 2"} {"level":"info","ts":"2026-02-10T15:01:32.392899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b received MsgVoteResp from 341a3703c39c1f5b at term 2"} {"level":"info","ts":"2026-02-10T15:01:32.392942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b became leader at term 2"} {"level":"info","ts":"2026-02-10T15:01:32.392997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 341a3703c39c1f5b elected leader 341a3703c39c1f5b at term 2"} {"level":"info","ts":"2026-02-10T15:01:32.396943Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T15:01:32.405021Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"341a3703c39c1f5b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.192:2379]}","request-path":"/0/members/341a3703c39c1f5b/attributes","cluster-id":"65341987fa712ba2","publish-timeout":"7s"} {"level":"info","ts":"2026-02-10T15:01:32.405151Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T15:01:32.406722Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-10T15:01:32.407053Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65341987fa712ba2","local-member-id":"341a3703c39c1f5b","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T15:01:32.407141Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T15:01:32.407174Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T15:01:32.407339Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-10T15:01:32.407353Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-10T15:01:32.408409Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.192:2379"} {"level":"info","ts":"2026-02-10T15:01:32.407194Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-10T15:01:42.318032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.217028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:01:42.318221Z","caller":"traceutil/trace.go:171","msg":"trace[1344154297] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"223.743143ms","start":"2026-02-10T15:01:42.094465Z","end":"2026-02-10T15:01:42.318208Z","steps":["trace[1344154297] 'process raft request' (duration: 97.680679ms)","trace[1344154297] 'compare' (duration: 124.934861ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:01:42.318159Z","caller":"traceutil/trace.go:171","msg":"trace[241212722] linearizableReadLoop","detail":"{readStateIndex:315; appliedIndex:314; }","duration":"177.43181ms","start":"2026-02-10T15:01:42.140712Z","end":"2026-02-10T15:01:42.318144Z","steps":["trace[241212722] 'read index received' (duration: 51.493788ms)","trace[241212722] 'applied index is now lower than readState.Index' (duration: 125.936942ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:01:42.318956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.229936ms","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-10T15:01:42.318998Z","caller":"traceutil/trace.go:171","msg":"trace[1162169018] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:307; }","duration":"178.277397ms","start":"2026-02-10T15:01:42.140708Z","end":"2026-02-10T15:01:42.318986Z","steps":["trace[1162169018] 'agreement among raft nodes before linearized reading' (duration: 178.161264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:42.319655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.611615ms","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-10T15:01:42.319792Z","caller":"traceutil/trace.go:171","msg":"trace[418950189] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:307; }","duration":"129.780468ms","start":"2026-02-10T15:01:42.189997Z","end":"2026-02-10T15:01:42.319778Z","steps":["trace[418950189] 'agreement among raft nodes before linearized reading' (duration: 129.616585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:42.320003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.496993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-10T15:01:42.320037Z","caller":"traceutil/trace.go:171","msg":"trace[985899145] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:307; }","duration":"121.539344ms","start":"2026-02-10T15:01:42.198486Z","end":"2026-02-10T15:01:42.320025Z","steps":["trace[985899145] 'agreement among raft nodes before linearized reading' (duration: 121.494373ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:42.319798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.369271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7237"} {"level":"info","ts":"2026-02-10T15:01:42.320189Z","caller":"traceutil/trace.go:171","msg":"trace[271588552] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:307; }","duration":"121.792939ms","start":"2026-02-10T15:01:42.198391Z","end":"2026-02-10T15:01:42.320184Z","steps":["trace[271588552] 'agreement among raft nodes before linearized reading' (duration: 121.31372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:42.320272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.165847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5270"} {"level":"info","ts":"2026-02-10T15:01:42.32029Z","caller":"traceutil/trace.go:171","msg":"trace[2027241044] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:307; }","duration":"122.191697ms","start":"2026-02-10T15:01:42.198094Z","end":"2026-02-10T15:01:42.320286Z","steps":["trace[2027241044] 'agreement among raft nodes before linearized reading' (duration: 122.160396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:42.320354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.264138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4260"} {"level":"info","ts":"2026-02-10T15:01:42.320372Z","caller":"traceutil/trace.go:171","msg":"trace[1219496999] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:307; }","duration":"122.293079ms","start":"2026-02-10T15:01:42.198075Z","end":"2026-02-10T15:01:42.320368Z","steps":["trace[1219496999] 'agreement among raft nodes before linearized reading' (duration: 122.262988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:42.320432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.380021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4597"} {"level":"info","ts":"2026-02-10T15:01:42.320449Z","caller":"traceutil/trace.go:171","msg":"trace[1919370480] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:307; }","duration":"122.409931ms","start":"2026-02-10T15:01:42.198035Z","end":"2026-02-10T15:01:42.320445Z","steps":["trace[1919370480] 'agreement among raft nodes before linearized reading' (duration: 122.381781ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:42.320278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.059264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"info","ts":"2026-02-10T15:01:42.320674Z","caller":"traceutil/trace.go:171","msg":"trace[547514497] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:307; }","duration":"122.549844ms","start":"2026-02-10T15:01:42.19811Z","end":"2026-02-10T15:01:42.32066Z","steps":["trace[547514497] 'agreement among raft nodes before linearized reading' (duration: 122.027723ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:42.626656Z","caller":"traceutil/trace.go:171","msg":"trace[1564762628] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"221.866366ms","start":"2026-02-10T15:01:42.404767Z","end":"2026-02-10T15:01:42.626633Z","steps":["trace[1564762628] 'process raft request' (duration: 146.294239ms)","trace[1564762628] 'compare' (duration: 75.487206ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:01:42.63026Z","caller":"traceutil/trace.go:171","msg":"trace[1321723323] linearizableReadLoop","detail":"{readStateIndex:321; appliedIndex:318; }","duration":"188.378918ms","start":"2026-02-10T15:01:42.441846Z","end":"2026-02-10T15:01:42.630225Z","steps":["trace[1321723323] 'read index received' (duration: 109.225249ms)","trace[1321723323] 'applied index is now lower than readState.Index' (duration: 79.152969ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:01:42.630381Z","caller":"traceutil/trace.go:171","msg":"trace[914626266] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"222.03999ms","start":"2026-02-10T15:01:42.408334Z","end":"2026-02-10T15:01:42.630374Z","steps":["trace[914626266] 'process raft request' (duration: 221.846926ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:42.630479Z","caller":"traceutil/trace.go:171","msg":"trace[1044770701] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"223.749903ms","start":"2026-02-10T15:01:42.406713Z","end":"2026-02-10T15:01:42.630462Z","steps":["trace[1044770701] 'process raft request' (duration: 223.336865ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:42.630644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.847427ms","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-10T15:01:42.630669Z","caller":"traceutil/trace.go:171","msg":"trace[1104900850] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:313; }","duration":"188.929368ms","start":"2026-02-10T15:01:42.441734Z","end":"2026-02-10T15:01:42.630663Z","steps":["trace[1104900850] 'agreement among raft nodes before linearized reading' (duration: 188.858787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:42.63078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.049003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-10T15:01:42.630811Z","caller":"traceutil/trace.go:171","msg":"trace[719222366] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:313; }","duration":"139.115504ms","start":"2026-02-10T15:01:42.491691Z","end":"2026-02-10T15:01:42.630806Z","steps":["trace[719222366] 'agreement among raft nodes before linearized reading' (duration: 139.071403ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:42.747337Z","caller":"traceutil/trace.go:171","msg":"trace[1000980700] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"102.284108ms","start":"2026-02-10T15:01:42.645039Z","end":"2026-02-10T15:01:42.747323Z","steps":["trace[1000980700] 'process raft request' (duration: 98.691335ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:59.498449Z","caller":"traceutil/trace.go:171","msg":"trace[1205775829] linearizableReadLoop","detail":"{readStateIndex:442; appliedIndex:441; }","duration":"425.679758ms","start":"2026-02-10T15:01:59.072748Z","end":"2026-02-10T15:01:59.498428Z","steps":["trace[1205775829] 'read index received' (duration: 425.333521ms)","trace[1205775829] 'applied index is now lower than readState.Index' (duration: 345.427µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:01:59.498709Z","caller":"traceutil/trace.go:171","msg":"trace[1072390477] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"614.017534ms","start":"2026-02-10T15:01:58.884678Z","end":"2026-02-10T15:01:59.498696Z","steps":["trace[1072390477] 'process raft request' (duration: 613.459722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:59.498964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:58.884644Z","time spent":"614.094326ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:01:59.499194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.448475ms","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-10T15:01:59.499231Z","caller":"traceutil/trace.go:171","msg":"trace[1832071378] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:429; }","duration":"426.518415ms","start":"2026-02-10T15:01:59.0727Z","end":"2026-02-10T15:01:59.499218Z","steps":["trace[1832071378] 'agreement among raft nodes before linearized reading' (duration: 426.459324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:59.500184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.933271ms","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-10T15:01:59.500239Z","caller":"traceutil/trace.go:171","msg":"trace[1943580626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:429; }","duration":"340.045244ms","start":"2026-02-10T15:01:59.16018Z","end":"2026-02-10T15:01:59.500225Z","steps":["trace[1943580626] 'agreement among raft nodes before linearized reading' (duration: 339.938011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:59.50028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:59.160164Z","time spent":"340.110175ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T15:01:59.500476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.186883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12673"} {"level":"info","ts":"2026-02-10T15:01:59.500492Z","caller":"traceutil/trace.go:171","msg":"trace[164207661] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:429; }","duration":"244.231623ms","start":"2026-02-10T15:01:59.256254Z","end":"2026-02-10T15:01:59.500485Z","steps":["trace[164207661] 'agreement among raft nodes before linearized reading' (duration: 244.134861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:59.50059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.533234ms","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-10T15:01:59.500607Z","caller":"traceutil/trace.go:171","msg":"trace[37769468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:429; }","duration":"339.549964ms","start":"2026-02-10T15:01:59.161052Z","end":"2026-02-10T15:01:59.500602Z","steps":["trace[37769468] 'agreement among raft nodes before linearized reading' (duration: 339.517414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:59.500623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:59.16103Z","time spent":"339.589095ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T15:01:59.499258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:59.072676Z","time spent":"426.575947ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-10T15:02:00.048812Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571471416926913,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T15:02:00.23447Z","caller":"traceutil/trace.go:171","msg":"trace[676733754] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"723.140696ms","start":"2026-02-10T15:01:59.511304Z","end":"2026-02-10T15:02:00.234444Z","steps":["trace[676733754] 'process raft request' (duration: 653.398695ms)","trace[676733754] 'compare' (duration: 69.088918ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:02:00.2346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:59.511286Z","time spent":"723.244529ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:02:00.238605Z","caller":"traceutil/trace.go:171","msg":"trace[1779369061] linearizableReadLoop","detail":"{readStateIndex:445; appliedIndex:442; }","duration":"690.585015ms","start":"2026-02-10T15:01:59.547974Z","end":"2026-02-10T15:02:00.238559Z","steps":["trace[1779369061] 'read index received' (duration: 616.74024ms)","trace[1779369061] 'applied index is now lower than readState.Index' (duration: 73.843455ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:02:00.238679Z","caller":"traceutil/trace.go:171","msg":"trace[342611800] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"721.600606ms","start":"2026-02-10T15:01:59.517073Z","end":"2026-02-10T15:02:00.238674Z","steps":["trace[342611800] 'process raft request' (duration: 721.361481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:00.238725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:59.517068Z","time spent":"721.628756ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:02:00.23878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.783969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-10T15:02:00.238818Z","caller":"traceutil/trace.go:171","msg":"trace[1126150219] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:432; }","duration":"690.860501ms","start":"2026-02-10T15:01:59.547944Z","end":"2026-02-10T15:02:00.238805Z","steps":["trace[1126150219] 'agreement among raft nodes before linearized reading' (duration: 690.775629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:00.238852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:59.547929Z","time spent":"690.914161ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-10T15:02:00.238642Z","caller":"traceutil/trace.go:171","msg":"trace[320661007] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"721.597946ms","start":"2026-02-10T15:01:59.517033Z","end":"2026-02-10T15:02:00.238631Z","steps":["trace[320661007] 'process raft request' (duration: 721.27655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:00.238991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:59.517026Z","time spent":"721.943913ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:02:04.675717Z","caller":"traceutil/trace.go:171","msg":"trace[641490113] linearizableReadLoop","detail":"{readStateIndex:488; appliedIndex:487; }","duration":"420.693098ms","start":"2026-02-10T15:02:04.255011Z","end":"2026-02-10T15:02:04.675704Z","steps":["trace[641490113] 'read index received' (duration: 420.562685ms)","trace[641490113] 'applied index is now lower than readState.Index' (duration: 129.923µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:02:04.675876Z","caller":"traceutil/trace.go:171","msg":"trace[493428216] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"423.191658ms","start":"2026-02-10T15:02:04.252678Z","end":"2026-02-10T15:02:04.67587Z","steps":["trace[493428216] 'process raft request' (duration: 422.946853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:04.675933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:04.252664Z","time spent":"423.225619ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:02:04.676061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.048745ms","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-10T15:02:04.676076Z","caller":"traceutil/trace.go:171","msg":"trace[338042843] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:474; }","duration":"421.072795ms","start":"2026-02-10T15:02:04.254998Z","end":"2026-02-10T15:02:04.676071Z","steps":["trace[338042843] 'agreement among raft nodes before linearized reading' (duration: 421.014094ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:04.676089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:04.254992Z","time spent":"421.093817ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T15:02:04.676225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.519701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:02:04.676238Z","caller":"traceutil/trace.go:171","msg":"trace[2117822038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:474; }","duration":"329.566811ms","start":"2026-02-10T15:02:04.346667Z","end":"2026-02-10T15:02:04.676234Z","steps":["trace[2117822038] 'agreement among raft nodes before linearized reading' (duration: 329.545021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:04.67625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:04.346648Z","time spent":"329.598703ms","remote":"127.0.0.1:44364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T15:02:04.676362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.33329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-10T15:02:04.676407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.31161ms","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-10T15:02:04.676429Z","caller":"traceutil/trace.go:171","msg":"trace[1611723475] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:474; }","duration":"418.3342ms","start":"2026-02-10T15:02:04.258088Z","end":"2026-02-10T15:02:04.676422Z","steps":["trace[1611723475] 'agreement among raft nodes before linearized reading' (duration: 418.284509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:04.676444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:04.258072Z","time spent":"418.367021ms","remote":"127.0.0.1:44610","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":"info","ts":"2026-02-10T15:02:04.676441Z","caller":"traceutil/trace.go:171","msg":"trace[1887608246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:474; }","duration":"420.450242ms","start":"2026-02-10T15:02:04.255964Z","end":"2026-02-10T15:02:04.676414Z","steps":["trace[1887608246] 'agreement among raft nodes before linearized reading' (duration: 420.181497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:04.676485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:04.255958Z","time spent":"420.518603ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T15:02:13.197153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.60343ms","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-10T15:02:13.197213Z","caller":"traceutil/trace.go:171","msg":"trace[1887465441] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:495; }","duration":"405.705771ms","start":"2026-02-10T15:02:12.791488Z","end":"2026-02-10T15:02:13.197194Z","steps":["trace[1887465441] 'range keys from in-memory index tree' (duration: 405.502458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:13.19724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:12.79147Z","time spent":"405.764813ms","remote":"127.0.0.1:44610","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-10T15:02:13.197377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.842885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-10T15:02:13.197422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.387967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:02:13.197437Z","caller":"traceutil/trace.go:171","msg":"trace[528645507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:495; }","duration":"851.427578ms","start":"2026-02-10T15:02:12.346005Z","end":"2026-02-10T15:02:13.197432Z","steps":["trace[528645507] 'range keys from in-memory index tree' (duration: 851.337336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:13.197449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:12.345991Z","time spent":"851.455818ms","remote":"127.0.0.1:44366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-10T15:02:13.197441Z","caller":"traceutil/trace.go:171","msg":"trace[601923389] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:495; }","duration":"402.947067ms","start":"2026-02-10T15:02:12.794477Z","end":"2026-02-10T15:02:13.197424Z","steps":["trace[601923389] 'range keys from in-memory index tree' (duration: 402.753083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:13.197476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:12.794457Z","time spent":"403.011238ms","remote":"127.0.0.1:44610","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-10T15:02:13.197558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.414695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vk6wj\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-10T15:02:13.197571Z","caller":"traceutil/trace.go:171","msg":"trace[449353669] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vk6wj; range_end:; response_count:1; response_revision:495; }","duration":"786.449325ms","start":"2026-02-10T15:02:12.411117Z","end":"2026-02-10T15:02:13.197567Z","steps":["trace[449353669] 'range keys from in-memory index tree' (duration: 786.322693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:13.197585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:12.411105Z","time spent":"786.477406ms","remote":"127.0.0.1:44528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16678,"request content":"key:\"/registry/pods/kube-system/cilium-vk6wj\" "} {"level":"warn","ts":"2026-02-10T15:02:13.197631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.598554ms","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-10T15:02:13.197661Z","caller":"traceutil/trace.go:171","msg":"trace[444487039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:495; }","duration":"358.668166ms","start":"2026-02-10T15:02:12.838984Z","end":"2026-02-10T15:02:13.197652Z","steps":["trace[444487039] 'range keys from in-memory index tree' (duration: 358.510092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:13.197688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:12.838961Z","time spent":"358.721126ms","remote":"127.0.0.1:44610","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-10T15:02:13.197818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.368035ms","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-10T15:02:13.197833Z","caller":"traceutil/trace.go:171","msg":"trace[1000531792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:495; }","duration":"347.411286ms","start":"2026-02-10T15:02:12.850418Z","end":"2026-02-10T15:02:13.197829Z","steps":["trace[1000531792] 'range keys from in-memory index tree' (duration: 347.249053ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:13.197846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:12.850403Z","time spent":"347.440226ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-10T15:02:13.345409Z","caller":"traceutil/trace.go:171","msg":"trace[178253465] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"142.903469ms","start":"2026-02-10T15:02:13.202458Z","end":"2026-02-10T15:02:13.345361Z","steps":["trace[178253465] 'process raft request' (duration: 142.823728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:13.851258Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571471416927060,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T15:02:13.897866Z","caller":"traceutil/trace.go:171","msg":"trace[2025244401] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:512; }","duration":"547.148202ms","start":"2026-02-10T15:02:13.350692Z","end":"2026-02-10T15:02:13.89784Z","steps":["trace[2025244401] 'read index received' (duration: 546.677221ms)","trace[2025244401] 'applied index is now lower than readState.Index' (duration: 470.371µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:02:13.898018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.297065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:02:13.898072Z","caller":"traceutil/trace.go:171","msg":"trace[1697425169] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:500; }","duration":"547.394386ms","start":"2026-02-10T15:02:13.35067Z","end":"2026-02-10T15:02:13.898064Z","steps":["trace[1697425169] 'agreement among raft nodes before linearized reading' (duration: 547.267804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:13.898102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:13.350648Z","time spent":"547.445218ms","remote":"127.0.0.1:44366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-10T15:02:13.898435Z","caller":"traceutil/trace.go:171","msg":"trace[295077074] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"694.823967ms","start":"2026-02-10T15:02:13.203599Z","end":"2026-02-10T15:02:13.898423Z","steps":["trace[295077074] 'process raft request' (duration: 693.853027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:13.898531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:13.203582Z","time spent":"694.879679ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:02:13.898648Z","caller":"traceutil/trace.go:171","msg":"trace[1246611180] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"694.987981ms","start":"2026-02-10T15:02:13.20365Z","end":"2026-02-10T15:02:13.898638Z","steps":["trace[1246611180] 'process raft request' (duration: 693.994571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:13.898695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:13.203646Z","time spent":"695.021191ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:02:13.898805Z","caller":"traceutil/trace.go:171","msg":"trace[537044267] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"694.346068ms","start":"2026-02-10T15:02:13.204451Z","end":"2026-02-10T15:02:13.898797Z","steps":["trace[537044267] 'process raft request' (duration: 693.299627ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:13.899642Z","caller":"traceutil/trace.go:171","msg":"trace[1180306364] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"686.022109ms","start":"2026-02-10T15:02:13.213605Z","end":"2026-02-10T15:02:13.899627Z","steps":["trace[1180306364] 'process raft request' (duration: 684.192722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:13.899705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:13.213597Z","time spent":"686.07615ms","remote":"127.0.0.1:44528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:02:13.899993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:13.204441Z","time spent":"694.383708ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:02:16.773186Z","caller":"traceutil/trace.go:171","msg":"trace[215422076] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"117.172346ms","start":"2026-02-10T15:02:16.655988Z","end":"2026-02-10T15:02:16.773161Z","steps":["trace[215422076] 'process raft request' (duration: 117.035203ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:19.255137Z","caller":"traceutil/trace.go:171","msg":"trace[811389634] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"121.943264ms","start":"2026-02-10T15:02:19.133179Z","end":"2026-02-10T15:02:19.255122Z","steps":["trace[811389634] 'process raft request' (duration: 121.609758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:21.475534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.231084ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-10T15:02:21.475601Z","caller":"traceutil/trace.go:171","msg":"trace[1613997425] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"190.57024ms","start":"2026-02-10T15:02:21.285019Z","end":"2026-02-10T15:02:21.475589Z","steps":["trace[1613997425] 'read index received' (duration: 1.250775ms)","trace[1613997425] 'applied index is now lower than readState.Index' (duration: 189.318375ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:02:21.475783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.752714ms","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-10T15:02:21.475809Z","caller":"traceutil/trace.go:171","msg":"trace[1529374482] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:587; }","duration":"190.811796ms","start":"2026-02-10T15:02:21.284991Z","end":"2026-02-10T15:02:21.475802Z","steps":["trace[1529374482] 'agreement among raft nodes before linearized reading' (duration: 190.758265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:21.475984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.886997ms","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-10T15:02:21.476055Z","caller":"traceutil/trace.go:171","msg":"trace[1035565452] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:587; }","duration":"190.967179ms","start":"2026-02-10T15:02:21.285053Z","end":"2026-02-10T15:02:21.47602Z","steps":["trace[1035565452] 'agreement among raft nodes before linearized reading' (duration: 190.867057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:21.476443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.604204ms","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-10T15:02:21.476465Z","caller":"traceutil/trace.go:171","msg":"trace[966179506] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:587; }","duration":"169.689756ms","start":"2026-02-10T15:02:21.30677Z","end":"2026-02-10T15:02:21.476459Z","steps":["trace[966179506] 'agreement among raft nodes before linearized reading' (duration: 169.625274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:21.476523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.442278ms","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-10T15:02:21.476584Z","caller":"traceutil/trace.go:171","msg":"trace[1034298784] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:587; }","duration":"191.506259ms","start":"2026-02-10T15:02:21.285067Z","end":"2026-02-10T15:02:21.476574Z","steps":["trace[1034298784] 'agreement among raft nodes before linearized reading' (duration: 191.426487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:21.476553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.538588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:02:21.476698Z","caller":"traceutil/trace.go:171","msg":"trace[840600527] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"127.70719ms","start":"2026-02-10T15:02:21.348986Z","end":"2026-02-10T15:02:21.476693Z","steps":["trace[840600527] 'agreement among raft nodes before linearized reading' (duration: 127.553697ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:23.184091Z","caller":"traceutil/trace.go:171","msg":"trace[1154543834] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"154.873734ms","start":"2026-02-10T15:02:23.029194Z","end":"2026-02-10T15:02:23.184068Z","steps":["trace[1154543834] 'process raft request' (duration: 154.754142ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:23.223732Z","caller":"traceutil/trace.go:171","msg":"trace[198716265] transaction","detail":"{read_only:false; number_of_response:0; response_revision:597; }","duration":"192.324548ms","start":"2026-02-10T15:02:23.031391Z","end":"2026-02-10T15:02:23.223715Z","steps":["trace[198716265] 'process raft request' (duration: 192.261897ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:23.315529Z","caller":"traceutil/trace.go:171","msg":"trace[1592996488] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"125.239699ms","start":"2026-02-10T15:02:23.190275Z","end":"2026-02-10T15:02:23.315515Z","steps":["trace[1592996488] 'process raft request' (duration: 122.885342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:26.849369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.392941ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:02:26.849489Z","caller":"traceutil/trace.go:171","msg":"trace[797202164] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"751.301009ms","start":"2026-02-10T15:02:26.098178Z","end":"2026-02-10T15:02:26.849479Z","steps":["trace[797202164] 'process raft request' (duration: 751.251738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:26.849517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:26.098163Z","time spent":"751.34285ms","remote":"127.0.0.1:60162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1236,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T15:02:26.849683Z","caller":"traceutil/trace.go:171","msg":"trace[1337065497] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"769.98869ms","start":"2026-02-10T15:02:26.079688Z","end":"2026-02-10T15:02:26.849677Z","steps":["trace[1337065497] 'process raft request' (duration: 322.229922ms)","trace[1337065497] 'compare' (duration: 447.207537ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:02:26.849718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:26.079678Z","time spent":"770.017071ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:02:26.849777Z","caller":"traceutil/trace.go:171","msg":"trace[1202858077] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"769.266086ms","start":"2026-02-10T15:02:26.080506Z","end":"2026-02-10T15:02:26.849772Z","steps":["trace[1202858077] 'read index received' (duration: 321.418846ms)","trace[1202858077] 'applied index is now lower than readState.Index' (duration: 447.84664ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:02:26.849924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.417739ms","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-10T15:02:26.84994Z","caller":"traceutil/trace.go:171","msg":"trace[75892352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:625; }","duration":"769.459689ms","start":"2026-02-10T15:02:26.080474Z","end":"2026-02-10T15:02:26.849934Z","steps":["trace[75892352] 'agreement among raft nodes before linearized reading' (duration: 769.394248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:26.849962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:26.080459Z","time spent":"769.497701ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T15:02:26.850043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.808784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.192\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-10T15:02:26.850055Z","caller":"traceutil/trace.go:171","msg":"trace[2010567105] range","detail":"{range_begin:/registry/masterleases/199.19.213.192; range_end:; response_count:1; response_revision:625; }","duration":"707.843275ms","start":"2026-02-10T15:02:26.142208Z","end":"2026-02-10T15:02:26.850051Z","steps":["trace[2010567105] 'agreement among raft nodes before linearized reading' (duration: 707.805994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:26.850066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:26.142196Z","time spent":"707.868145ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.192\" "} {"level":"warn","ts":"2026-02-10T15:02:26.850189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.403668ms","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-10T15:02:26.850202Z","caller":"traceutil/trace.go:171","msg":"trace[2114208860] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:625; }","duration":"415.43957ms","start":"2026-02-10T15:02:26.434758Z","end":"2026-02-10T15:02:26.850198Z","steps":["trace[2114208860] 'agreement among raft nodes before linearized reading' (duration: 415.399459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:26.850213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:26.434745Z","time spent":"415.46525ms","remote":"127.0.0.1:44610","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-10T15:02:26.850268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.89616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:02:26.85028Z","caller":"traceutil/trace.go:171","msg":"trace[111992812] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"501.932421ms","start":"2026-02-10T15:02:26.348343Z","end":"2026-02-10T15:02:26.850275Z","steps":["trace[111992812] 'agreement among raft nodes before linearized reading' (duration: 501.914631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:26.85029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:26.348329Z","time spent":"501.958371ms","remote":"127.0.0.1:44364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T15:02:27.366472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.134921ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-02-10T15:02:27.366533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:26.855005Z","time spent":"511.525077ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-10T15:02:27.379799Z","caller":"traceutil/trace.go:171","msg":"trace[1581539254] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"516.189552ms","start":"2026-02-10T15:02:26.863597Z","end":"2026-02-10T15:02:27.379786Z","steps":["trace[1581539254] 'process raft request' (duration: 516.09516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:27.379878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:26.863586Z","time spent":"516.257283ms","remote":"127.0.0.1:60134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2262,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T15:02:27.379965Z","caller":"traceutil/trace.go:171","msg":"trace[1386784415] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"524.743416ms","start":"2026-02-10T15:02:26.855194Z","end":"2026-02-10T15:02:27.379938Z","steps":["trace[1386784415] 'process raft request' (duration: 524.399389ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:27.380009Z","caller":"traceutil/trace.go:171","msg":"trace[1210857891] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"524.635084ms","start":"2026-02-10T15:02:26.855368Z","end":"2026-02-10T15:02:27.380003Z","steps":["trace[1210857891] 'process raft request' (duration: 524.306987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:27.380047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:26.855363Z","time spent":"524.659654ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:02:27.380095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:26.855187Z","time spent":"524.835457ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:02:28.72059Z","caller":"traceutil/trace.go:171","msg":"trace[1450836770] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"206.198359ms","start":"2026-02-10T15:02:28.514376Z","end":"2026-02-10T15:02:28.720575Z","steps":["trace[1450836770] 'process raft request' (duration: 206.109108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:29.185159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.843944ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:02:29.185247Z","caller":"traceutil/trace.go:171","msg":"trace[672667078] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"314.111666ms","start":"2026-02-10T15:02:28.871119Z","end":"2026-02-10T15:02:29.18523Z","steps":["trace[672667078] 'process raft request' (duration: 49.13147ms)","trace[672667078] 'compare' (duration: 264.66117ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:02:29.185301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:28.871112Z","time spent":"314.162877ms","remote":"127.0.0.1:44528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4833,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:02:29.229965Z","caller":"traceutil/trace.go:171","msg":"trace[2066870345] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"345.226041ms","start":"2026-02-10T15:02:28.884721Z","end":"2026-02-10T15:02:29.229947Z","steps":["trace[2066870345] 'process raft request' (duration: 345.19403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:29.230199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:28.884713Z","time spent":"345.419034ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:02:29.230467Z","caller":"traceutil/trace.go:171","msg":"trace[117029644] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"347.324223ms","start":"2026-02-10T15:02:28.883137Z","end":"2026-02-10T15:02:29.230462Z","steps":["trace[117029644] 'process raft request' (duration: 346.68497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:29.230563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:28.883127Z","time spent":"347.417615ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3830,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:02:29.307566Z","caller":"traceutil/trace.go:171","msg":"trace[1501375602] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"111.690244ms","start":"2026-02-10T15:02:29.19586Z","end":"2026-02-10T15:02:29.30755Z","steps":["trace[1501375602] 'process raft request' (duration: 105.629991ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:29.30774Z","caller":"traceutil/trace.go:171","msg":"trace[1020179903] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"107.622691ms","start":"2026-02-10T15:02:29.200109Z","end":"2026-02-10T15:02:29.307732Z","steps":["trace[1020179903] 'process raft request' (duration: 107.028289ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:29.352689Z","caller":"traceutil/trace.go:171","msg":"trace[968875321] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"106.203582ms","start":"2026-02-10T15:02:29.24647Z","end":"2026-02-10T15:02:29.352674Z","steps":["trace[968875321] 'process raft request' (duration: 106.175041ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:29.353611Z","caller":"traceutil/trace.go:171","msg":"trace[1895025072] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"109.141083ms","start":"2026-02-10T15:02:29.244456Z","end":"2026-02-10T15:02:29.353597Z","steps":["trace[1895025072] 'process raft request' (duration: 108.097181ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:29.596762Z","caller":"traceutil/trace.go:171","msg":"trace[513256627] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:685; }","duration":"215.634781ms","start":"2026-02-10T15:02:29.381113Z","end":"2026-02-10T15:02:29.596748Z","steps":["trace[513256627] 'read index received' (duration: 215.086349ms)","trace[513256627] 'applied index is now lower than readState.Index' (duration: 547.932µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:02:29.596905Z","caller":"traceutil/trace.go:171","msg":"trace[1613445812] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"222.620604ms","start":"2026-02-10T15:02:29.374279Z","end":"2026-02-10T15:02:29.596899Z","steps":["trace[1613445812] 'process raft request' (duration: 221.880289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:29.597017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.894326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:02:29.597034Z","caller":"traceutil/trace.go:171","msg":"trace[1684471748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:664; }","duration":"215.951138ms","start":"2026-02-10T15:02:29.381076Z","end":"2026-02-10T15:02:29.597027Z","steps":["trace[1684471748] 'agreement among raft nodes before linearized reading' (duration: 215.914317ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:29.603154Z","caller":"traceutil/trace.go:171","msg":"trace[609948475] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"219.478539ms","start":"2026-02-10T15:02:29.383667Z","end":"2026-02-10T15:02:29.603146Z","steps":["trace[609948475] 'process raft request' (duration: 219.368107ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:29.603261Z","caller":"traceutil/trace.go:171","msg":"trace[778773782] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"222.047481ms","start":"2026-02-10T15:02:29.381191Z","end":"2026-02-10T15:02:29.603239Z","steps":["trace[778773782] 'process raft request' (duration: 221.757325ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:29.603303Z","caller":"traceutil/trace.go:171","msg":"trace[979888207] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"219.582231ms","start":"2026-02-10T15:02:29.383716Z","end":"2026-02-10T15:02:29.603298Z","steps":["trace[979888207] 'process raft request' (duration: 219.377237ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:29.603566Z","caller":"traceutil/trace.go:171","msg":"trace[1880325232] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"218.406478ms","start":"2026-02-10T15:02:29.385152Z","end":"2026-02-10T15:02:29.603558Z","steps":["trace[1880325232] 'process raft request' (duration: 217.966509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:29.603868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.670293ms","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-10T15:02:29.603888Z","caller":"traceutil/trace.go:171","msg":"trace[1129188545] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:668; }","duration":"217.715634ms","start":"2026-02-10T15:02:29.386167Z","end":"2026-02-10T15:02:29.603882Z","steps":["trace[1129188545] 'agreement among raft nodes before linearized reading' (duration: 217.635353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:29.604027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.745954ms","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-10T15:02:29.604041Z","caller":"traceutil/trace.go:171","msg":"trace[155341435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:668; }","duration":"218.794805ms","start":"2026-02-10T15:02:29.385242Z","end":"2026-02-10T15:02:29.604037Z","steps":["trace[155341435] 'agreement among raft nodes before linearized reading' (duration: 218.742704ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:35.795267Z","caller":"traceutil/trace.go:171","msg":"trace[1374258186] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"102.810333ms","start":"2026-02-10T15:02:35.692433Z","end":"2026-02-10T15:02:35.795243Z","steps":["trace[1374258186] 'process raft request' (duration: 102.66061ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:36.27345Z","caller":"traceutil/trace.go:171","msg":"trace[127944150] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"472.445091ms","start":"2026-02-10T15:02:35.800989Z","end":"2026-02-10T15:02:36.273434Z","steps":["trace[127944150] 'process raft request' (duration: 464.0108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:36.273557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:35.800976Z","time spent":"472.519353ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:02:36.442147Z","caller":"traceutil/trace.go:171","msg":"trace[1958677565] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:711; }","duration":"579.540333ms","start":"2026-02-10T15:02:35.862589Z","end":"2026-02-10T15:02:36.44213Z","steps":["trace[1958677565] 'read index received' (duration: 402.418806ms)","trace[1958677565] 'applied index is now lower than readState.Index' (duration: 177.120587ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:02:36.442441Z","caller":"traceutil/trace.go:171","msg":"trace[900220468] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"636.926501ms","start":"2026-02-10T15:02:35.805506Z","end":"2026-02-10T15:02:36.442433Z","steps":["trace[900220468] 'process raft request' (duration: 579.558413ms)","trace[900220468] 'compare' (duration: 56.920839ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:02:36.442516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:35.805493Z","time spent":"636.969001ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:02:36.442638Z","caller":"traceutil/trace.go:171","msg":"trace[1897190713] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"584.228257ms","start":"2026-02-10T15:02:35.858401Z","end":"2026-02-10T15:02:36.442629Z","steps":["trace[1897190713] 'process raft request' (duration: 583.670645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:36.442673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:35.858385Z","time spent":"584.266988ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:02:36.442758Z","caller":"traceutil/trace.go:171","msg":"trace[1024313602] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"567.769412ms","start":"2026-02-10T15:02:35.874982Z","end":"2026-02-10T15:02:36.442752Z","steps":["trace[1024313602] 'process raft request' (duration: 567.117639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:36.442843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:35.874975Z","time spent":"567.797052ms","remote":"127.0.0.1:44422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T15:02:36.44296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.353659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T15:02:36.442983Z","caller":"traceutil/trace.go:171","msg":"trace[732408695] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:692; }","duration":"580.38995ms","start":"2026-02-10T15:02:35.862586Z","end":"2026-02-10T15:02:36.442976Z","steps":["trace[732408695] 'agreement among raft nodes before linearized reading' (duration: 580.338068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:36.443004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:35.862564Z","time spent":"580.43505ms","remote":"127.0.0.1:44842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":29,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-02-10T15:02:36.443152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.427378ms","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-10T15:02:36.44317Z","caller":"traceutil/trace.go:171","msg":"trace[635563845] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:692; }","duration":"326.445338ms","start":"2026-02-10T15:02:36.116719Z","end":"2026-02-10T15:02:36.443165Z","steps":["trace[635563845] 'agreement among raft nodes before linearized reading' (duration: 326.375657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:36.443201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:36.116698Z","time spent":"326.49886ms","remote":"127.0.0.1:44610","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-10T15:02:36.44349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.722845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.192\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-10T15:02:36.443514Z","caller":"traceutil/trace.go:171","msg":"trace[1000650486] range","detail":"{range_begin:/registry/masterleases/199.19.213.192; range_end:; response_count:1; response_revision:692; }","duration":"300.777956ms","start":"2026-02-10T15:02:36.14273Z","end":"2026-02-10T15:02:36.443508Z","steps":["trace[1000650486] 'agreement among raft nodes before linearized reading' (duration: 300.709135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:36.443531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:36.142715Z","time spent":"300.812147ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.192\" "} {"level":"info","ts":"2026-02-10T15:02:36.640643Z","caller":"traceutil/trace.go:171","msg":"trace[6861716] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"153.521826ms","start":"2026-02-10T15:02:36.487105Z","end":"2026-02-10T15:02:36.640627Z","steps":["trace[6861716] 'process raft request' (duration: 153.493505ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:36.640865Z","caller":"traceutil/trace.go:171","msg":"trace[482631179] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"194.643714ms","start":"2026-02-10T15:02:36.446215Z","end":"2026-02-10T15:02:36.640859Z","steps":["trace[482631179] 'process raft request' (duration: 113.328058ms)","trace[482631179] 'compare' (duration: 80.980639ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:02:37.953832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.903618ms","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-10T15:02:37.953907Z","caller":"traceutil/trace.go:171","msg":"trace[1867803603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:694; }","duration":"684.007679ms","start":"2026-02-10T15:02:37.269871Z","end":"2026-02-10T15:02:37.953879Z","steps":["trace[1867803603] 'range keys from in-memory index tree' (duration: 683.773845ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:37.953949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:37.269856Z","time spent":"684.084981ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T15:02:37.954112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.901984ms","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-10T15:02:37.954156Z","caller":"traceutil/trace.go:171","msg":"trace[433860724] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:694; }","duration":"151.980675ms","start":"2026-02-10T15:02:37.802165Z","end":"2026-02-10T15:02:37.954145Z","steps":["trace[433860724] 'range keys from in-memory index tree' (duration: 151.802971ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:38.274232Z","caller":"traceutil/trace.go:171","msg":"trace[1321940780] linearizableReadLoop","detail":"{readStateIndex:720; appliedIndex:719; }","duration":"147.34861ms","start":"2026-02-10T15:02:38.126867Z","end":"2026-02-10T15:02:38.274215Z","steps":["trace[1321940780] 'read index received' (duration: 115.709795ms)","trace[1321940780] 'applied index is now lower than readState.Index' (duration: 31.638005ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:02:38.274329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.445152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:02:38.27435Z","caller":"traceutil/trace.go:171","msg":"trace[1658251283] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:696; }","duration":"147.504603ms","start":"2026-02-10T15:02:38.126839Z","end":"2026-02-10T15:02:38.274344Z","steps":["trace[1658251283] 'agreement among raft nodes before linearized reading' (duration: 147.436522ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:38.274533Z","caller":"traceutil/trace.go:171","msg":"trace[430408684] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"313.624927ms","start":"2026-02-10T15:02:37.960899Z","end":"2026-02-10T15:02:38.274524Z","steps":["trace[430408684] 'process raft request' (duration: 281.728127ms)","trace[430408684] 'compare' (duration: 31.279367ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:02:38.274616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:37.960889Z","time spent":"313.660867ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:02:38.694724Z","caller":"traceutil/trace.go:171","msg":"trace[1785960801] linearizableReadLoop","detail":"{readStateIndex:721; appliedIndex:720; }","duration":"347.560887ms","start":"2026-02-10T15:02:38.347146Z","end":"2026-02-10T15:02:38.694707Z","steps":["trace[1785960801] 'read index received' (duration: 347.438945ms)","trace[1785960801] 'applied index is now lower than readState.Index' (duration: 121.312µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:02:38.694795Z","caller":"traceutil/trace.go:171","msg":"trace[1605056029] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"415.385548ms","start":"2026-02-10T15:02:38.279404Z","end":"2026-02-10T15:02:38.694789Z","steps":["trace[1605056029] 'process raft request' (duration: 415.234445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:38.69487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:38.279387Z","time spent":"415.42561ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:02:38.694937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.836615ms","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-10T15:02:38.69498Z","caller":"traceutil/trace.go:171","msg":"trace[1173667332] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:697; }","duration":"240.922436ms","start":"2026-02-10T15:02:38.454046Z","end":"2026-02-10T15:02:38.694969Z","steps":["trace[1173667332] 'agreement among raft nodes before linearized reading' (duration: 240.802323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:38.695123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.73972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T15:02:38.695137Z","caller":"traceutil/trace.go:171","msg":"trace[763153502] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:697; }","duration":"205.78474ms","start":"2026-02-10T15:02:38.489348Z","end":"2026-02-10T15:02:38.695132Z","steps":["trace[763153502] 'agreement among raft nodes before linearized reading' (duration: 205.729499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:38.695149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.002326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:02:38.695169Z","caller":"traceutil/trace.go:171","msg":"trace[1863842883] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:697; }","duration":"348.046648ms","start":"2026-02-10T15:02:38.347117Z","end":"2026-02-10T15:02:38.695163Z","steps":["trace[1863842883] 'agreement among raft nodes before linearized reading' (duration: 348.020207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:38.695181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:38.347104Z","time spent":"348.074228ms","remote":"127.0.0.1:44364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-10T15:02:52.943162Z","caller":"traceutil/trace.go:171","msg":"trace[824116366] linearizableReadLoop","detail":"{readStateIndex:770; appliedIndex:769; }","duration":"155.56034ms","start":"2026-02-10T15:02:52.787582Z","end":"2026-02-10T15:02:52.943142Z","steps":["trace[824116366] 'read index received' (duration: 155.396585ms)","trace[824116366] 'applied index is now lower than readState.Index' (duration: 162.915µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:02:52.943266Z","caller":"traceutil/trace.go:171","msg":"trace[1589893956] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"175.948623ms","start":"2026-02-10T15:02:52.76731Z","end":"2026-02-10T15:02:52.943259Z","steps":["trace[1589893956] 'process raft request' (duration: 175.688797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:52.943468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.452637ms","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-10T15:02:52.943528Z","caller":"traceutil/trace.go:171","msg":"trace[2135829154] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:742; }","duration":"150.539578ms","start":"2026-02-10T15:02:52.792971Z","end":"2026-02-10T15:02:52.943511Z","steps":["trace[2135829154] 'agreement among raft nodes before linearized reading' (duration: 150.382125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:52.943625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.015709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-10T15:02:52.943656Z","caller":"traceutil/trace.go:171","msg":"trace[1413788814] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:742; }","duration":"156.090411ms","start":"2026-02-10T15:02:52.787555Z","end":"2026-02-10T15:02:52.943646Z","steps":["trace[1413788814] 'agreement among raft nodes before linearized reading' (duration: 155.975928ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:54.546111Z","caller":"traceutil/trace.go:171","msg":"trace[1366345923] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"148.493227ms","start":"2026-02-10T15:02:54.397594Z","end":"2026-02-10T15:02:54.546087Z","steps":["trace[1366345923] 'process raft request' (duration: 148.367434ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:55.094945Z","caller":"traceutil/trace.go:171","msg":"trace[25239173] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"139.124177ms","start":"2026-02-10T15:02:54.955784Z","end":"2026-02-10T15:02:55.094908Z","steps":["trace[25239173] 'process raft request' (duration: 138.955684ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:55.645649Z","caller":"traceutil/trace.go:171","msg":"trace[1264322354] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:774; }","duration":"664.451353ms","start":"2026-02-10T15:02:54.981177Z","end":"2026-02-10T15:02:55.645628Z","steps":["trace[1264322354] 'read index received' (duration: 113.697562ms)","trace[1264322354] 'applied index is now lower than readState.Index' (duration: 550.752701ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:02:55.645914Z","caller":"traceutil/trace.go:171","msg":"trace[427546798] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"686.717134ms","start":"2026-02-10T15:02:54.959184Z","end":"2026-02-10T15:02:55.645901Z","steps":["trace[427546798] 'process raft request' (duration: 673.005387ms)","trace[427546798] 'compare' (duration: 12.83618ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:02:55.646011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:54.959177Z","time spent":"686.765565ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:02:55.646179Z","caller":"traceutil/trace.go:171","msg":"trace[1904765269] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"686.873227ms","start":"2026-02-10T15:02:54.959294Z","end":"2026-02-10T15:02:55.646167Z","steps":["trace[1904765269] 'process raft request' (duration: 686.259494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:55.64623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:54.959267Z","time spent":"686.933788ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:02:55.646378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.195498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T15:02:55.646411Z","caller":"traceutil/trace.go:171","msg":"trace[1781273117] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:749; }","duration":"665.26062ms","start":"2026-02-10T15:02:54.981139Z","end":"2026-02-10T15:02:55.646399Z","steps":["trace[1781273117] 'agreement among raft nodes before linearized reading' (duration: 665.196348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:55.646439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:54.981125Z","time spent":"665.306761ms","remote":"127.0.0.1:44884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-02-10T15:02:55.64658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.666851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:02:55.646609Z","caller":"traceutil/trace.go:171","msg":"trace[1291172071] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:749; }","duration":"474.753413ms","start":"2026-02-10T15:02:55.171849Z","end":"2026-02-10T15:02:55.646602Z","steps":["trace[1291172071] 'agreement among raft nodes before linearized reading' (duration: 474.708352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:55.646635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:55.171796Z","time spent":"474.832015ms","remote":"127.0.0.1:44648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-02-10T15:03:11.379861Z","caller":"traceutil/trace.go:171","msg":"trace[489308512] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"110.420907ms","start":"2026-02-10T15:03:11.269427Z","end":"2026-02-10T15:03:11.379848Z","steps":["trace[489308512] 'process raft request' (duration: 33.652292ms)","trace[489308512] 'compare' (duration: 76.440518ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:03:11.547807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.734402ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-10T15:03:26.861707Z","caller":"traceutil/trace.go:171","msg":"trace[465908173] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"202.101502ms","start":"2026-02-10T15:03:26.659588Z","end":"2026-02-10T15:03:26.86169Z","steps":["trace[465908173] 'process raft request' (duration: 202.01262ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:27.571103Z","caller":"traceutil/trace.go:171","msg":"trace[1041134463] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"107.699341ms","start":"2026-02-10T15:03:27.463386Z","end":"2026-02-10T15:03:27.571085Z","steps":["trace[1041134463] 'process raft request' (duration: 94.426452ms)","trace[1041134463] 'compare' (duration: 13.200488ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:04:26.156207Z","caller":"traceutil/trace.go:171","msg":"trace[1846986985] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"127.141547ms","start":"2026-02-10T15:04:26.029039Z","end":"2026-02-10T15:04:26.156181Z","steps":["trace[1846986985] 'process raft request' (duration: 28.685684ms)","trace[1846986985] 'compare' (duration: 97.8608ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:05:55.368864Z","caller":"traceutil/trace.go:171","msg":"trace[185733826] transaction","detail":"{read_only:false; response_revision:1537; number_of_response:1; }","duration":"187.876146ms","start":"2026-02-10T15:05:55.180935Z","end":"2026-02-10T15:05:55.368811Z","steps":["trace[185733826] 'process raft request' (duration: 111.065708ms)","trace[185733826] 'compare' (duration: 76.506182ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:06:01.286152Z","caller":"traceutil/trace.go:171","msg":"trace[1910883014] transaction","detail":"{read_only:false; response_revision:1560; number_of_response:1; }","duration":"108.951536ms","start":"2026-02-10T15:06:01.177184Z","end":"2026-02-10T15:06:01.286135Z","steps":["trace[1910883014] 'process raft request' (duration: 108.847384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:06:03.10427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.053282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:06:03.120563Z","caller":"traceutil/trace.go:171","msg":"trace[590172469] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1568; }","duration":"185.166855ms","start":"2026-02-10T15:06:02.919159Z","end":"2026-02-10T15:06:03.104326Z","steps":["trace[590172469] 'count revisions from in-memory index tree' (duration: 184.94839ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:06:03.881709Z","caller":"traceutil/trace.go:171","msg":"trace[948694075] transaction","detail":"{read_only:false; response_revision:1570; number_of_response:1; }","duration":"104.672092ms","start":"2026-02-10T15:06:03.777012Z","end":"2026-02-10T15:06:03.881684Z","steps":["trace[948694075] 'process raft request' (duration: 104.546779ms)"],"step_count":1}