{"level":"warn","ts":"2026-02-05T17:36:19.740657Z","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-05T17:36:19.741122Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.23:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.23:2380","--initial-cluster=instance=https://199.204.45.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.23: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-05T17:36:19.741291Z","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-05T17:36:19.741335Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-02-05T17:36:19.741641Z","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-05T17:36:19.742288Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"]} {"level":"info","ts":"2026-02-05T17:36:19.742437Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.23: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-05T17:36:19.748093Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.360485ms"} {"level":"info","ts":"2026-02-05T17:36:19.761822Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"51a5c416a4d98c86","cluster-id":"ade2045974c19907"} {"level":"info","ts":"2026-02-05T17:36:19.761896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=()"} {"level":"info","ts":"2026-02-05T17:36:19.761914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 0"} {"level":"info","ts":"2026-02-05T17:36:19.761922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 51a5c416a4d98c86 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-05T17:36:19.761935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 1"} {"level":"info","ts":"2026-02-05T17:36:19.761978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"warn","ts":"2026-02-05T17:36:19.773115Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-05T17:36:19.774448Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-05T17:36:19.780606Z","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-05T17:36:19.783009Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"51a5c416a4d98c86","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-05T17:36:19.785662Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"51a5c416a4d98c86","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-05T17:36:19.785773Z","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-05T17:36:19.785798Z","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-05T17:36:19.785805Z","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-05T17:36:19.785955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"info","ts":"2026-02-05T17:36:19.78601Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","added-peer-id":"51a5c416a4d98c86","added-peer-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-02-05T17:36:19.804518Z","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-05T17:36:19.804725Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"51a5c416a4d98c86","initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-05T17:36:19.804751Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-05T17:36:19.805257Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-02-05T17:36:19.805272Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-02-05T17:36:20.362694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 is starting a new election at term 1"} {"level":"info","ts":"2026-02-05T17:36:20.362734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-05T17:36:20.362752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgPreVoteResp from 51a5c416a4d98c86 at term 1"} {"level":"info","ts":"2026-02-05T17:36:20.362765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became candidate at term 2"} {"level":"info","ts":"2026-02-05T17:36:20.362869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgVoteResp from 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-02-05T17:36:20.362891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became leader at term 2"} {"level":"info","ts":"2026-02-05T17:36:20.362937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 51a5c416a4d98c86 elected leader 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-02-05T17:36:20.403218Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"51a5c416a4d98c86","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.23:2379]}","request-path":"/0/members/51a5c416a4d98c86/attributes","cluster-id":"ade2045974c19907","publish-timeout":"7s"} {"level":"info","ts":"2026-02-05T17:36:20.403373Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T17:36:20.403704Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:36:20.403752Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T17:36:20.404208Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-05T17:36:20.404243Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-05T17:36:20.406333Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.23:2379"} {"level":"info","ts":"2026-02-05T17:36:20.438086Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:36:20.438173Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:36:20.438787Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:36:20.443564Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-05T17:36:24.08714Z","caller":"traceutil/trace.go:171","msg":"trace[878173935] transaction","detail":"{read_only:false; response_revision:202; number_of_response:1; }","duration":"120.135556ms","start":"2026-02-05T17:36:23.966986Z","end":"2026-02-05T17:36:24.087122Z","steps":["trace[878173935] 'process raft request' (duration: 119.96698ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:36:24.750984Z","caller":"traceutil/trace.go:171","msg":"trace[2121507880] transaction","detail":"{read_only:false; response_revision:224; number_of_response:1; }","duration":"106.618584ms","start":"2026-02-05T17:36:24.644342Z","end":"2026-02-05T17:36:24.750961Z","steps":["trace[2121507880] 'process raft request' (duration: 97.828914ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:36:26.292374Z","caller":"traceutil/trace.go:171","msg":"trace[1150447785] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"111.522233ms","start":"2026-02-05T17:36:26.180608Z","end":"2026-02-05T17:36:26.29213Z","steps":["trace[1150447785] 'process raft request' (duration: 43.290336ms)","trace[1150447785] 'compare' (duration: 67.969593ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:36:26.292712Z","caller":"traceutil/trace.go:171","msg":"trace[26622217] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"111.927335ms","start":"2026-02-05T17:36:26.180768Z","end":"2026-02-05T17:36:26.292696Z","steps":["trace[26622217] 'process raft request' (duration: 111.656341ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:36:26.293769Z","caller":"traceutil/trace.go:171","msg":"trace[2119618868] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"103.495881ms","start":"2026-02-05T17:36:26.190262Z","end":"2026-02-05T17:36:26.293758Z","steps":["trace[2119618868] 'process raft request' (duration: 102.323607ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:36:26.501384Z","caller":"traceutil/trace.go:171","msg":"trace[2079014856] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"141.573822ms","start":"2026-02-05T17:36:26.359793Z","end":"2026-02-05T17:36:26.501366Z","steps":["trace[2079014856] 'process raft request' (duration: 141.523463ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:36:26.501626Z","caller":"traceutil/trace.go:171","msg":"trace[3305494] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"188.227855ms","start":"2026-02-05T17:36:26.313389Z","end":"2026-02-05T17:36:26.501617Z","steps":["trace[3305494] 'process raft request' (duration: 93.941212ms)","trace[3305494] 'compare' (duration: 93.890362ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:36:27.298927Z","caller":"traceutil/trace.go:171","msg":"trace[1631838028] linearizableReadLoop","detail":"{readStateIndex:297; appliedIndex:296; }","duration":"139.529894ms","start":"2026-02-05T17:36:27.159361Z","end":"2026-02-05T17:36:27.298891Z","steps":["trace[1631838028] 'read index received' (duration: 65.193384ms)","trace[1631838028] 'applied index is now lower than readState.Index' (duration: 74.335001ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:36:27.302833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.439611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:36:27.302938Z","caller":"traceutil/trace.go:171","msg":"trace[720064180] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:0; response_revision:285; }","duration":"143.570318ms","start":"2026-02-05T17:36:27.159335Z","end":"2026-02-05T17:36:27.302905Z","steps":["trace[720064180] 'agreement among raft nodes before linearized reading' (duration: 139.677321ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:36:27.303335Z","caller":"traceutil/trace.go:171","msg":"trace[1455835159] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"169.518791ms","start":"2026-02-05T17:36:27.133792Z","end":"2026-02-05T17:36:27.30331Z","steps":["trace[1455835159] 'process raft request' (duration: 90.813662ms)","trace[1455835159] 'compare' (duration: 74.115546ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:36:27.625153Z","caller":"traceutil/trace.go:171","msg":"trace[1758989042] linearizableReadLoop","detail":"{readStateIndex:303; appliedIndex:302; }","duration":"166.145192ms","start":"2026-02-05T17:36:27.458959Z","end":"2026-02-05T17:36:27.625104Z","steps":["trace[1758989042] 'read index received' (duration: 120.854478ms)","trace[1758989042] 'applied index is now lower than readState.Index' (duration: 45.289284ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:36:27.625331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.333548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:36:27.625365Z","caller":"traceutil/trace.go:171","msg":"trace[1917165417] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:0; response_revision:291; }","duration":"166.439145ms","start":"2026-02-05T17:36:27.458911Z","end":"2026-02-05T17:36:27.62535Z","steps":["trace[1917165417] 'agreement among raft nodes before linearized reading' (duration: 166.321288ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:36:27.625813Z","caller":"traceutil/trace.go:171","msg":"trace[857369067] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"169.465473ms","start":"2026-02-05T17:36:27.456331Z","end":"2026-02-05T17:36:27.625797Z","steps":["trace[857369067] 'process raft request' (duration: 123.439374ms)","trace[857369067] 'compare' (duration: 45.173666ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:36:27.863533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.71652ms","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-05T17:36:27.863632Z","caller":"traceutil/trace.go:171","msg":"trace[1474087795] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:293; }","duration":"103.890396ms","start":"2026-02-05T17:36:27.759719Z","end":"2026-02-05T17:36:27.86361Z","steps":["trace[1474087795] 'range keys from in-memory index tree' (duration: 103.596743ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:36:29.180487Z","caller":"traceutil/trace.go:171","msg":"trace[2076470585] linearizableReadLoop","detail":"{readStateIndex:323; appliedIndex:322; }","duration":"130.203184ms","start":"2026-02-05T17:36:29.050251Z","end":"2026-02-05T17:36:29.180454Z","steps":["trace[2076470585] 'read index received' (duration: 98.29107ms)","trace[2076470585] 'applied index is now lower than readState.Index' (duration: 31.910954ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:36:29.180944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.668613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3840"} {"level":"info","ts":"2026-02-05T17:36:29.181329Z","caller":"traceutil/trace.go:171","msg":"trace[221288303] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"131.339631ms","start":"2026-02-05T17:36:29.049963Z","end":"2026-02-05T17:36:29.181303Z","steps":["trace[221288303] 'process raft request' (duration: 98.629613ms)","trace[221288303] 'compare' (duration: 31.698737ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:36:29.181884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.562227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3840"} {"level":"info","ts":"2026-02-05T17:36:29.181936Z","caller":"traceutil/trace.go:171","msg":"trace[307645214] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:311; }","duration":"131.620885ms","start":"2026-02-05T17:36:29.050299Z","end":"2026-02-05T17:36:29.18192Z","steps":["trace[307645214] 'agreement among raft nodes before linearized reading' (duration: 131.540726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:29.183206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.445545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-02-05T17:36:29.184063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.263253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3840"} {"level":"info","ts":"2026-02-05T17:36:29.184079Z","caller":"traceutil/trace.go:171","msg":"trace[1687496755] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:311; }","duration":"125.309587ms","start":"2026-02-05T17:36:29.058731Z","end":"2026-02-05T17:36:29.18404Z","steps":["trace[1687496755] 'agreement among raft nodes before linearized reading' (duration: 124.420083ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:36:29.181146Z","caller":"traceutil/trace.go:171","msg":"trace[1872593757] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:311; }","duration":"130.899869ms","start":"2026-02-05T17:36:29.050232Z","end":"2026-02-05T17:36:29.181131Z","steps":["trace[1872593757] 'agreement among raft nodes before linearized reading' (duration: 130.491477ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:36:29.184828Z","caller":"traceutil/trace.go:171","msg":"trace[437064956] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:311; }","duration":"132.38751ms","start":"2026-02-05T17:36:29.051772Z","end":"2026-02-05T17:36:29.18416Z","steps":["trace[437064956] 'agreement among raft nodes before linearized reading' (duration: 132.259452ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:29.183988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.46158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3840"} {"level":"info","ts":"2026-02-05T17:36:29.185328Z","caller":"traceutil/trace.go:171","msg":"trace[1968829362] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:311; }","duration":"134.746484ms","start":"2026-02-05T17:36:29.050489Z","end":"2026-02-05T17:36:29.185236Z","steps":["trace[1968829362] 'agreement among raft nodes before linearized reading' (duration: 133.405421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:29.185679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.109048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3840"} {"level":"info","ts":"2026-02-05T17:36:29.187608Z","caller":"traceutil/trace.go:171","msg":"trace[1275625260] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:311; }","duration":"137.04824ms","start":"2026-02-05T17:36:29.050548Z","end":"2026-02-05T17:36:29.187597Z","steps":["trace[1275625260] 'agreement among raft nodes before linearized reading' (duration: 135.099907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:29.186445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.899993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3840"} {"level":"info","ts":"2026-02-05T17:36:29.188727Z","caller":"traceutil/trace.go:171","msg":"trace[1729713709] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:311; }","duration":"138.196928ms","start":"2026-02-05T17:36:29.05052Z","end":"2026-02-05T17:36:29.188717Z","steps":["trace[1729713709] 'agreement among raft nodes before linearized reading' (duration: 135.822104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:30.14747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.127492ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:36:30.151694Z","caller":"traceutil/trace.go:171","msg":"trace[663166801] linearizableReadLoop","detail":"{readStateIndex:333; appliedIndex:329; }","duration":"791.430581ms","start":"2026-02-05T17:36:29.360229Z","end":"2026-02-05T17:36:30.15166Z","steps":["trace[663166801] 'read index received' (duration: 190.367339ms)","trace[663166801] 'applied index is now lower than readState.Index' (duration: 601.062112ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:36:30.152358Z","caller":"traceutil/trace.go:171","msg":"trace[1237647529] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"835.694366ms","start":"2026-02-05T17:36:29.316643Z","end":"2026-02-05T17:36:30.152337Z","steps":["trace[1237647529] 'process raft request' (duration: 233.939398ms)","trace[1237647529] 'compare' (duration: 595.998445ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:36:30.152475Z","caller":"traceutil/trace.go:171","msg":"trace[477624949] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"822.155309ms","start":"2026-02-05T17:36:29.330294Z","end":"2026-02-05T17:36:30.15245Z","steps":["trace[477624949] 'process raft request' (duration: 821.232216ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:30.152875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:29.31663Z","time spent":"835.912432ms","remote":"127.0.0.1:44246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:36:30.1534Z","caller":"traceutil/trace.go:171","msg":"trace[1076963026] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"823.001323ms","start":"2026-02-05T17:36:29.330383Z","end":"2026-02-05T17:36:30.153385Z","steps":["trace[1076963026] 'process raft request' (duration: 821.227655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:30.153092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:29.330285Z","time spent":"822.274586ms","remote":"127.0.0.1:44056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4711,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:36:30.153657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:29.330363Z","time spent":"823.17031ms","remote":"127.0.0.1:43924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T17:36:30.154094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.862746ms","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-05T17:36:30.154394Z","caller":"traceutil/trace.go:171","msg":"trace[1842523017] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:321; }","duration":"794.19248ms","start":"2026-02-05T17:36:29.360187Z","end":"2026-02-05T17:36:30.154379Z","steps":["trace[1842523017] 'agreement among raft nodes before linearized reading' (duration: 793.788517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:30.154569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:29.360172Z","time spent":"794.379717ms","remote":"127.0.0.1:44082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-02-05T17:36:30.155346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.377863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-05T17:36:30.155521Z","caller":"traceutil/trace.go:171","msg":"trace[1644026182] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:321; }","duration":"741.608139ms","start":"2026-02-05T17:36:29.413899Z","end":"2026-02-05T17:36:30.155507Z","steps":["trace[1644026182] 'agreement among raft nodes before linearized reading' (duration: 741.397103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:30.155691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:29.413878Z","time spent":"741.792604ms","remote":"127.0.0.1:44082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"info","ts":"2026-02-05T17:36:30.154415Z","caller":"traceutil/trace.go:171","msg":"trace[1751172769] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"837.062371ms","start":"2026-02-05T17:36:29.317341Z","end":"2026-02-05T17:36:30.154403Z","steps":["trace[1751172769] 'process raft request' (duration: 833.90932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:30.156279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:29.317334Z","time spent":"838.831019ms","remote":"127.0.0.1:44164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T17:36:30.156986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.857933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-05T17:36:30.157141Z","caller":"traceutil/trace.go:171","msg":"trace[207760067] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:321; }","duration":"694.069809ms","start":"2026-02-05T17:36:29.463061Z","end":"2026-02-05T17:36:30.157131Z","steps":["trace[207760067] 'agreement among raft nodes before linearized reading' (duration: 693.216405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:30.157421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:29.463044Z","time spent":"694.367034ms","remote":"127.0.0.1:44082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"info","ts":"2026-02-05T17:36:31.298204Z","caller":"traceutil/trace.go:171","msg":"trace[1140744326] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"657.328909ms","start":"2026-02-05T17:36:30.640818Z","end":"2026-02-05T17:36:31.298146Z","steps":["trace[1140744326] 'process raft request' (duration: 657.17882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:31.298402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:30.640807Z","time spent":"657.481906ms","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:36:31.691481Z","caller":"traceutil/trace.go:171","msg":"trace[1035463745] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"226.082968ms","start":"2026-02-05T17:36:31.465381Z","end":"2026-02-05T17:36:31.691464Z","steps":["trace[1035463745] 'process raft request' (duration: 225.99211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:36.430245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.90607ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:36:36.43096Z","caller":"traceutil/trace.go:171","msg":"trace[248625140] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"404.039149ms","start":"2026-02-05T17:36:36.026906Z","end":"2026-02-05T17:36:36.430945Z","steps":["trace[248625140] 'process raft request' (duration: 95.383028ms)","trace[248625140] 'compare' (duration: 307.574375ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:36:36.431034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:36.02688Z","time spent":"404.129198ms","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:36:38.797824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.346108ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:36:38.797995Z","caller":"traceutil/trace.go:171","msg":"trace[414783474] linearizableReadLoop","detail":"{readStateIndex:397; appliedIndex:396; }","duration":"209.040181ms","start":"2026-02-05T17:36:38.588888Z","end":"2026-02-05T17:36:38.797928Z","steps":["trace[414783474] 'read index received' (duration: 74.511576ms)","trace[414783474] 'applied index is now lower than readState.Index' (duration: 134.527285ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:36:38.798139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.250059ms","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-05T17:36:38.798172Z","caller":"traceutil/trace.go:171","msg":"trace[1958470771] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:382; }","duration":"209.310268ms","start":"2026-02-05T17:36:38.58885Z","end":"2026-02-05T17:36:38.79816Z","steps":["trace[1958470771] 'agreement among raft nodes before linearized reading' (duration: 209.17857ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:36:38.79893Z","caller":"traceutil/trace.go:171","msg":"trace[417831748] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"283.595886ms","start":"2026-02-05T17:36:38.514962Z","end":"2026-02-05T17:36:38.798558Z","steps":["trace[417831748] 'process raft request' (duration: 148.428349ms)","trace[417831748] 'compare' (duration: 134.213509ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:36:39.363349Z","caller":"traceutil/trace.go:171","msg":"trace[2105746769] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"102.669176ms","start":"2026-02-05T17:36:39.260658Z","end":"2026-02-05T17:36:39.363327Z","steps":["trace[2105746769] 'process raft request' (duration: 102.120892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:41.377471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.157563ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:36:41.377581Z","caller":"traceutil/trace.go:171","msg":"trace[449392664] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"537.017229ms","start":"2026-02-05T17:36:40.840555Z","end":"2026-02-05T17:36:41.377572Z","steps":["trace[449392664] 'process raft request' (duration: 536.977109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:41.377619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:40.840544Z","time spent":"537.054679ms","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:36:41.377709Z","caller":"traceutil/trace.go:171","msg":"trace[467111029] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"546.556899ms","start":"2026-02-05T17:36:40.831146Z","end":"2026-02-05T17:36:41.377703Z","steps":["trace[467111029] 'process raft request' (duration: 156.128569ms)","trace[467111029] 'compare' (duration: 389.931645ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:36:41.377729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:40.831142Z","time spent":"546.577639ms","remote":"127.0.0.1:43924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T17:36:41.377784Z","caller":"traceutil/trace.go:171","msg":"trace[2106431695] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:420; }","duration":"546.592219ms","start":"2026-02-05T17:36:40.831188Z","end":"2026-02-05T17:36:41.37778Z","steps":["trace[2106431695] 'read index received' (duration: 3.465214ms)","trace[2106431695] 'applied index is now lower than readState.Index' (duration: 543.126505ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:36:41.378808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.816199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-5dd5756b68\" ","response":"range_response_count:1 size:3797"} {"level":"info","ts":"2026-02-05T17:36:41.378832Z","caller":"traceutil/trace.go:171","msg":"trace[938766624] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-5dd5756b68; range_end:; response_count:1; response_revision:406; }","duration":"543.852388ms","start":"2026-02-05T17:36:40.834971Z","end":"2026-02-05T17:36:41.378824Z","steps":["trace[938766624] 'agreement among raft nodes before linearized reading' (duration: 543.811579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:41.378846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:40.834946Z","time spent":"543.897607ms","remote":"127.0.0.1:44392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":3820,"request content":"key:\"/registry/replicasets/kube-system/coredns-5dd5756b68\" "} {"level":"warn","ts":"2026-02-05T17:36:41.378929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.783154ms","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-05T17:36:41.378942Z","caller":"traceutil/trace.go:171","msg":"trace[960113822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:406; }","duration":"493.823384ms","start":"2026-02-05T17:36:40.885115Z","end":"2026-02-05T17:36:41.378938Z","steps":["trace[960113822] 'agreement among raft nodes before linearized reading' (duration: 493.799294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:41.378953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:40.8851Z","time spent":"493.851023ms","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T17:36:41.379009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.193481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4150"} {"level":"info","ts":"2026-02-05T17:36:41.37902Z","caller":"traceutil/trace.go:171","msg":"trace[1726922451] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:406; }","duration":"531.22814ms","start":"2026-02-05T17:36:40.847788Z","end":"2026-02-05T17:36:41.379017Z","steps":["trace[1726922451] 'agreement among raft nodes before linearized reading' (duration: 531.206541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:41.379031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:40.847778Z","time spent":"531.2504ms","remote":"127.0.0.1:44366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4173,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-05T17:36:41.379281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.091394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-77cccfdc44\" ","response":"range_response_count:1 size:3788"} {"level":"info","ts":"2026-02-05T17:36:41.379295Z","caller":"traceutil/trace.go:171","msg":"trace[805829435] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-77cccfdc44; range_end:; response_count:1; response_revision:406; }","duration":"548.120483ms","start":"2026-02-05T17:36:40.83117Z","end":"2026-02-05T17:36:41.379291Z","steps":["trace[805829435] 'agreement among raft nodes before linearized reading' (duration: 546.705077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:41.379308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:40.831166Z","time spent":"548.137983ms","remote":"127.0.0.1:44392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":3811,"request content":"key:\"/registry/replicasets/kube-system/coredns-77cccfdc44\" "} {"level":"warn","ts":"2026-02-05T17:36:41.756453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.58746ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:36:41.756545Z","caller":"traceutil/trace.go:171","msg":"trace[696757587] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"355.701443ms","start":"2026-02-05T17:36:41.400825Z","end":"2026-02-05T17:36:41.756527Z","steps":["trace[696757587] 'process raft request' (duration: 105.967035ms)","trace[696757587] 'compare' (duration: 249.092795ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:36:41.7566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:41.400809Z","time spent":"355.765812ms","remote":"127.0.0.1:44366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4135,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:36:41.759428Z","caller":"traceutil/trace.go:171","msg":"trace[1093282686] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:423; }","duration":"358.321547ms","start":"2026-02-05T17:36:41.40109Z","end":"2026-02-05T17:36:41.759412Z","steps":["trace[1093282686] 'read index received' (duration: 105.800676ms)","trace[1093282686] 'applied index is now lower than readState.Index' (duration: 252.520011ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:36:41.759658Z","caller":"traceutil/trace.go:171","msg":"trace[469983120] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"358.702254ms","start":"2026-02-05T17:36:41.400946Z","end":"2026-02-05T17:36:41.759648Z","steps":["trace[469983120] 'process raft request' (duration: 358.320597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:41.759726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:41.400935Z","time spent":"358.748803ms","remote":"127.0.0.1:44392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3818,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:36:41.759842Z","caller":"traceutil/trace.go:171","msg":"trace[1083484625] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"354.151218ms","start":"2026-02-05T17:36:41.405685Z","end":"2026-02-05T17:36:41.759837Z","steps":["trace[1083484625] 'process raft request' (duration: 353.660853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:41.759877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:41.405673Z","time spent":"354.183417ms","remote":"127.0.0.1:44392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3782,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:36:41.760018Z","caller":"traceutil/trace.go:171","msg":"trace[1142491439] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"354.259136ms","start":"2026-02-05T17:36:41.405752Z","end":"2026-02-05T17:36:41.760011Z","steps":["trace[1142491439] 'process raft request' (duration: 353.624632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:41.760051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:41.405748Z","time spent":"354.285426ms","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:36:41.760131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.05361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:36:41.760149Z","caller":"traceutil/trace.go:171","msg":"trace[55499088] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:410; }","duration":"359.07904ms","start":"2026-02-05T17:36:41.401064Z","end":"2026-02-05T17:36:41.760143Z","steps":["trace[55499088] 'agreement among raft nodes before linearized reading' (duration: 359.04864ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:41.762343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:41.401059Z","time spent":"361.229219ms","remote":"127.0.0.1:43860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T17:36:41.760573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.82799ms","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-05T17:36:41.762841Z","caller":"traceutil/trace.go:171","msg":"trace[44685680] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:410; }","duration":"357.111498ms","start":"2026-02-05T17:36:41.405717Z","end":"2026-02-05T17:36:41.762828Z","steps":["trace[44685680] 'agreement among raft nodes before linearized reading' (duration: 354.816411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:41.762941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:41.405714Z","time spent":"357.214517ms","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-05T17:36:49.840525Z","caller":"traceutil/trace.go:171","msg":"trace[726473839] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"412.979689ms","start":"2026-02-05T17:36:49.427531Z","end":"2026-02-05T17:36:49.840511Z","steps":["trace[726473839] 'process raft request' (duration: 412.65081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:49.840612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:49.427523Z","time spent":"413.042599ms","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:36:56.076831Z","caller":"traceutil/trace.go:171","msg":"trace[2057501105] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:504; }","duration":"158.070812ms","start":"2026-02-05T17:36:55.918744Z","end":"2026-02-05T17:36:56.076815Z","steps":["trace[2057501105] 'read index received' (duration: 157.952592ms)","trace[2057501105] 'applied index is now lower than readState.Index' (duration: 117.18µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:36:56.076919Z","caller":"traceutil/trace.go:171","msg":"trace[1699145337] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"184.596935ms","start":"2026-02-05T17:36:55.892315Z","end":"2026-02-05T17:36:56.076912Z","steps":["trace[1699145337] 'process raft request' (duration: 184.398375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:56.07703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.276592ms","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-05T17:36:56.077054Z","caller":"traceutil/trace.go:171","msg":"trace[480096602] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:485; }","duration":"158.372512ms","start":"2026-02-05T17:36:55.918675Z","end":"2026-02-05T17:36:56.077048Z","steps":["trace[480096602] 'agreement among raft nodes before linearized reading' (duration: 158.322992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:56.077102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.547967ms","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-05T17:36:56.077113Z","caller":"traceutil/trace.go:171","msg":"trace[328062011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:485; }","duration":"122.583767ms","start":"2026-02-05T17:36:55.954525Z","end":"2026-02-05T17:36:56.077109Z","steps":["trace[328062011] 'agreement among raft nodes before linearized reading' (duration: 122.564867ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:36:56.221661Z","caller":"traceutil/trace.go:171","msg":"trace[739439089] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"141.410056ms","start":"2026-02-05T17:36:56.080231Z","end":"2026-02-05T17:36:56.221641Z","steps":["trace[739439089] 'process raft request' (duration: 88.832071ms)","trace[739439089] 'compare' (duration: 52.434245ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:36:56.221811Z","caller":"traceutil/trace.go:171","msg":"trace[1544898862] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"139.821395ms","start":"2026-02-05T17:36:56.081984Z","end":"2026-02-05T17:36:56.221805Z","steps":["trace[1544898862] 'process raft request' (duration: 139.624655ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:36:58.835041Z","caller":"traceutil/trace.go:171","msg":"trace[1430639280] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"116.012838ms","start":"2026-02-05T17:36:58.719005Z","end":"2026-02-05T17:36:58.835018Z","steps":["trace[1430639280] 'process raft request' (duration: 115.924948ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:36:59.054202Z","caller":"traceutil/trace.go:171","msg":"trace[1561745786] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"121.503951ms","start":"2026-02-05T17:36:58.932679Z","end":"2026-02-05T17:36:59.054183Z","steps":["trace[1561745786] 'process raft request' (duration: 58.8185ms)","trace[1561745786] 'compare' (duration: 62.62144ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:36:59.108257Z","caller":"traceutil/trace.go:171","msg":"trace[1704419714] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"175.320864ms","start":"2026-02-05T17:36:58.932915Z","end":"2026-02-05T17:36:59.108236Z","steps":["trace[1704419714] 'process raft request' (duration: 175.202384ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:36:59.689571Z","caller":"traceutil/trace.go:171","msg":"trace[2033897054] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:556; }","duration":"607.988096ms","start":"2026-02-05T17:36:59.081564Z","end":"2026-02-05T17:36:59.689552Z","steps":["trace[2033897054] 'read index received' (duration: 26.650651ms)","trace[2033897054] 'applied index is now lower than readState.Index' (duration: 581.336875ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:36:59.689677Z","caller":"traceutil/trace.go:171","msg":"trace[508200131] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"608.211296ms","start":"2026-02-05T17:36:59.08146Z","end":"2026-02-05T17:36:59.689671Z","steps":["trace[508200131] 'process raft request' (duration: 599.740443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:59.689729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:59.081443Z","time spent":"608.250096ms","remote":"127.0.0.1:43892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T17:36:59.68985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.279546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:36:59.689867Z","caller":"traceutil/trace.go:171","msg":"trace[810005909] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:537; }","duration":"608.314346ms","start":"2026-02-05T17:36:59.081548Z","end":"2026-02-05T17:36:59.689862Z","steps":["trace[810005909] 'agreement among raft nodes before linearized reading' (duration: 608.266436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:59.689882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:59.081543Z","time spent":"608.336156ms","remote":"127.0.0.1:57498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 "} {"level":"info","ts":"2026-02-05T17:36:59.838638Z","caller":"traceutil/trace.go:171","msg":"trace[1556867631] linearizableReadLoop","detail":"{readStateIndex:562; appliedIndex:558; }","duration":"148.982601ms","start":"2026-02-05T17:36:59.68961Z","end":"2026-02-05T17:36:59.838593Z","steps":["trace[1556867631] 'read index received' (duration: 148.0625ms)","trace[1556867631] 'applied index is now lower than readState.Index' (duration: 919.261µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:36:59.838727Z","caller":"traceutil/trace.go:171","msg":"trace[365884126] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"724.933098ms","start":"2026-02-05T17:36:59.113784Z","end":"2026-02-05T17:36:59.838717Z","steps":["trace[365884126] 'process raft request' (duration: 723.875696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:59.838822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.518252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:36:59.838878Z","caller":"traceutil/trace.go:171","msg":"trace[1787239038] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:541; }","duration":"746.589112ms","start":"2026-02-05T17:36:59.092277Z","end":"2026-02-05T17:36:59.838866Z","steps":["trace[1787239038] 'agreement among raft nodes before linearized reading' (duration: 746.467832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:59.838902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:59.092272Z","time spent":"746.623482ms","remote":"127.0.0.1:57504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-02-05T17:36:59.838896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.618582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:36:59.83893Z","caller":"traceutil/trace.go:171","msg":"trace[743558268] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:541; }","duration":"746.659402ms","start":"2026-02-05T17:36:59.092261Z","end":"2026-02-05T17:36:59.83892Z","steps":["trace[743558268] 'agreement among raft nodes before linearized reading' (duration: 746.601772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:59.83896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:59.092258Z","time spent":"746.694372ms","remote":"127.0.0.1:57504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 "} {"level":"info","ts":"2026-02-05T17:36:59.83901Z","caller":"traceutil/trace.go:171","msg":"trace[1450825248] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"576.856596ms","start":"2026-02-05T17:36:59.262147Z","end":"2026-02-05T17:36:59.839004Z","steps":["trace[1450825248] 'process raft request' (duration: 576.319895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:59.839053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:59.262129Z","time spent":"576.894996ms","remote":"127.0.0.1:43892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T17:36:59.839133Z","caller":"traceutil/trace.go:171","msg":"trace[1904279622] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"376.009509ms","start":"2026-02-05T17:36:59.463119Z","end":"2026-02-05T17:36:59.839128Z","steps":["trace[1904279622] 'process raft request' (duration: 375.390978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:59.839153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:59.463095Z","time spent":"376.048389ms","remote":"127.0.0.1:43892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T17:36:59.839213Z","caller":"traceutil/trace.go:171","msg":"trace[1907095736] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"178.824868ms","start":"2026-02-05T17:36:59.660382Z","end":"2026-02-05T17:36:59.839207Z","steps":["trace[1907095736] 'process raft request' (duration: 178.176747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:59.83927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.844959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:36:59.839282Z","caller":"traceutil/trace.go:171","msg":"trace[217346550] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:541; }","duration":"438.887649ms","start":"2026-02-05T17:36:59.400391Z","end":"2026-02-05T17:36:59.839278Z","steps":["trace[217346550] 'agreement among raft nodes before linearized reading' (duration: 438.863819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:59.839294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:59.400374Z","time spent":"438.916679ms","remote":"127.0.0.1:43860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T17:36:59.838826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:59.113767Z","time spent":"724.986078ms","remote":"127.0.0.1:43892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4068,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:36:59.839587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.950769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:36:59.839604Z","caller":"traceutil/trace.go:171","msg":"trace[45093681] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:541; }","duration":"757.994169ms","start":"2026-02-05T17:36:59.081605Z","end":"2026-02-05T17:36:59.839599Z","steps":["trace[45093681] 'agreement among raft nodes before linearized reading' (duration: 757.967649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:59.839626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:59.081589Z","time spent":"758.032789ms","remote":"127.0.0.1:57498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-02-05T17:36:59.839694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.459853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:36:59.839706Z","caller":"traceutil/trace.go:171","msg":"trace[2042032130] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:541; }","duration":"747.472683ms","start":"2026-02-05T17:36:59.09223Z","end":"2026-02-05T17:36:59.839702Z","steps":["trace[2042032130] 'agreement among raft nodes before linearized reading' (duration: 747.453093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:59.839719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:59.092224Z","time spent":"747.491143ms","remote":"127.0.0.1:57504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 "} {"level":"warn","ts":"2026-02-05T17:36:59.839797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.201579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:36:59.83981Z","caller":"traceutil/trace.go:171","msg":"trace[246517477] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:541; }","duration":"758.222469ms","start":"2026-02-05T17:36:59.081584Z","end":"2026-02-05T17:36:59.839806Z","steps":["trace[246517477] 'agreement among raft nodes before linearized reading' (duration: 758.203549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:59.839822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:59.08158Z","time spent":"758.238399ms","remote":"127.0.0.1:57498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 "} {"level":"info","ts":"2026-02-05T17:36:59.922927Z","caller":"traceutil/trace.go:171","msg":"trace[231951436] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"224.334399ms","start":"2026-02-05T17:36:59.698576Z","end":"2026-02-05T17:36:59.92291Z","steps":["trace[231951436] 'process raft request' (duration: 224.239409ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:00.019987Z","caller":"traceutil/trace.go:171","msg":"trace[1279970012] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"160.051689ms","start":"2026-02-05T17:36:59.85992Z","end":"2026-02-05T17:37:00.019972Z","steps":["trace[1279970012] 'process raft request' (duration: 160.025459ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:00.020066Z","caller":"traceutil/trace.go:171","msg":"trace[1787245738] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"162.037891ms","start":"2026-02-05T17:36:59.857986Z","end":"2026-02-05T17:37:00.020024Z","steps":["trace[1787245738] 'process raft request' (duration: 157.773302ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:00.119133Z","caller":"traceutil/trace.go:171","msg":"trace[1480194288] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:563; }","duration":"183.505319ms","start":"2026-02-05T17:36:59.935608Z","end":"2026-02-05T17:37:00.119113Z","steps":["trace[1480194288] 'read index received' (duration: 80.223371ms)","trace[1480194288] 'applied index is now lower than readState.Index' (duration: 103.280398ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:37:00.119618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.96616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T17:37:00.119651Z","caller":"traceutil/trace.go:171","msg":"trace[1269371060] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:546; }","duration":"184.05296ms","start":"2026-02-05T17:36:59.93559Z","end":"2026-02-05T17:37:00.119643Z","steps":["trace[1269371060] 'agreement among raft nodes before linearized reading' (duration: 183.623459ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:00.120528Z","caller":"traceutil/trace.go:171","msg":"trace[123081808] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"189.123917ms","start":"2026-02-05T17:36:59.931395Z","end":"2026-02-05T17:37:00.120519Z","steps":["trace[123081808] 'process raft request' (duration: 187.413924ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:00.125388Z","caller":"traceutil/trace.go:171","msg":"trace[775303094] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"190.994543ms","start":"2026-02-05T17:36:59.934379Z","end":"2026-02-05T17:37:00.125374Z","steps":["trace[775303094] 'process raft request' (duration: 184.535919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:01.55149Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.122585518s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-05T17:37:01.553308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.197427188s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:37:01.553469Z","caller":"traceutil/trace.go:171","msg":"trace[1861801645] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"1.161236822s","start":"2026-02-05T17:37:00.392222Z","end":"2026-02-05T17:37:01.553459Z","steps":["trace[1861801645] 'process raft request' (duration: 1.161196342s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:01.553507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:00.392204Z","time spent":"1.161288762s","remote":"127.0.0.1:43892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T17:37:01.553686Z","caller":"traceutil/trace.go:171","msg":"trace[844587955] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"1.383448341s","start":"2026-02-05T17:37:00.170232Z","end":"2026-02-05T17:37:01.55368Z","steps":["trace[844587955] 'process raft request' (duration: 185.577332ms)","trace[844587955] 'compare' (duration: 1.197197368s)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:37:01.553719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:00.170219Z","time spent":"1.383485331s","remote":"127.0.0.1:43892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24220,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:37:01.553811Z","caller":"traceutil/trace.go:171","msg":"trace[586748988] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"1.374173991s","start":"2026-02-05T17:37:00.17963Z","end":"2026-02-05T17:37:01.553804Z","steps":["trace[586748988] 'process raft request' (duration: 1.37371828s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:01.553852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:00.179613Z","time spent":"1.374218621s","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:37:01.553924Z","caller":"traceutil/trace.go:171","msg":"trace[1130121520] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:570; }","duration":"1.24060908s","start":"2026-02-05T17:37:00.313309Z","end":"2026-02-05T17:37:01.553918Z","steps":["trace[1130121520] 'read index received' (duration: 42.44289ms)","trace[1130121520] 'applied index is now lower than readState.Index' (duration: 1.19816546s)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:37:01.554108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.24079786s","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-05T17:37:01.554129Z","caller":"traceutil/trace.go:171","msg":"trace[1858103797] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:552; }","duration":"1.24084166s","start":"2026-02-05T17:37:00.313283Z","end":"2026-02-05T17:37:01.554124Z","steps":["trace[1858103797] 'agreement among raft nodes before linearized reading' (duration: 1.24077499s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:01.554143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:00.31327Z","time spent":"1.24087049s","remote":"127.0.0.1:44160","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-05T17:37:01.554229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.161011802s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-05T17:37:01.554247Z","caller":"traceutil/trace.go:171","msg":"trace[545557688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:552; }","duration":"1.161043362s","start":"2026-02-05T17:37:00.393199Z","end":"2026-02-05T17:37:01.554243Z","steps":["trace[545557688] 'agreement among raft nodes before linearized reading' (duration: 1.160998962s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:01.554263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:00.393193Z","time spent":"1.161067592s","remote":"127.0.0.1:44160","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-05T17:37:01.554427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.210408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:37:01.554446Z","caller":"traceutil/trace.go:171","msg":"trace[485195568] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:552; }","duration":"154.253008ms","start":"2026-02-05T17:37:01.400188Z","end":"2026-02-05T17:37:01.554441Z","steps":["trace[485195568] 'agreement among raft nodes before linearized reading' (duration: 154.228078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:01.554532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.006871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-05T17:37:01.554551Z","caller":"traceutil/trace.go:171","msg":"trace[1988139359] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:552; }","duration":"296.048191ms","start":"2026-02-05T17:37:01.258499Z","end":"2026-02-05T17:37:01.554547Z","steps":["trace[1988139359] 'agreement among raft nodes before linearized reading' (duration: 296.003251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:01.554627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.949821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-05T17:37:01.554644Z","caller":"traceutil/trace.go:171","msg":"trace[373534356] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:552; }","duration":"495.985481ms","start":"2026-02-05T17:37:01.058655Z","end":"2026-02-05T17:37:01.55464Z","steps":["trace[373534356] 'agreement among raft nodes before linearized reading' (duration: 495.94481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:01.55466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:01.058646Z","time spent":"496.011231ms","remote":"127.0.0.1:43892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4106,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-02-05T17:37:01.554739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.146543ms","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-05T17:37:01.554759Z","caller":"traceutil/trace.go:171","msg":"trace[1711475326] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:552; }","duration":"497.192954ms","start":"2026-02-05T17:37:01.057562Z","end":"2026-02-05T17:37:01.554755Z","steps":["trace[1711475326] 'agreement among raft nodes before linearized reading' (duration: 497.145474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:01.554779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:01.057548Z","time spent":"497.227074ms","remote":"127.0.0.1:44160","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-05T17:37:01.554856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.302106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-05T17:37:01.554874Z","caller":"traceutil/trace.go:171","msg":"trace[776495603] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:552; }","duration":"694.319427ms","start":"2026-02-05T17:37:00.860549Z","end":"2026-02-05T17:37:01.554869Z","steps":["trace[776495603] 'agreement among raft nodes before linearized reading' (duration: 694.280836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:01.554889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:00.860461Z","time spent":"694.425168ms","remote":"127.0.0.1:43892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4203,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-02-05T17:37:01.554979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.496422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-05T17:37:01.555Z","caller":"traceutil/trace.go:171","msg":"trace[2046493377] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:552; }","duration":"895.586672ms","start":"2026-02-05T17:37:00.659408Z","end":"2026-02-05T17:37:01.554995Z","steps":["trace[2046493377] 'agreement among raft nodes before linearized reading' (duration: 895.535432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:01.555016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:00.659391Z","time spent":"895.622052ms","remote":"127.0.0.1:43892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9343,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-05T17:37:01.555375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.096114676s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-05T17:37:01.555461Z","caller":"traceutil/trace.go:171","msg":"trace[1439210517] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:552; }","duration":"1.096251966s","start":"2026-02-05T17:37:00.459191Z","end":"2026-02-05T17:37:01.555443Z","steps":["trace[1439210517] 'agreement among raft nodes before linearized reading' (duration: 1.096069286s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:01.555517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:00.459178Z","time spent":"1.096330507s","remote":"127.0.0.1:43892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5200,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"info","ts":"2026-02-05T17:37:01.723577Z","caller":"traceutil/trace.go:171","msg":"trace[870547118] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"128.083749ms","start":"2026-02-05T17:37:01.595476Z","end":"2026-02-05T17:37:01.723559Z","steps":["trace[870547118] 'process raft request' (duration: 119.184456ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:01.745685Z","caller":"traceutil/trace.go:171","msg":"trace[1818994302] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"150.068047ms","start":"2026-02-05T17:37:01.595601Z","end":"2026-02-05T17:37:01.745669Z","steps":["trace[1818994302] 'process raft request' (duration: 149.513145ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:01.745801Z","caller":"traceutil/trace.go:171","msg":"trace[171966328] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"150.140128ms","start":"2026-02-05T17:37:01.595653Z","end":"2026-02-05T17:37:01.745794Z","steps":["trace[171966328] 'process raft request' (duration: 149.586395ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:01.74598Z","caller":"traceutil/trace.go:171","msg":"trace[1783950471] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"134.344005ms","start":"2026-02-05T17:37:01.611629Z","end":"2026-02-05T17:37:01.745973Z","steps":["trace[1783950471] 'process raft request' (duration: 133.655312ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:01.972574Z","caller":"traceutil/trace.go:171","msg":"trace[649048616] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"154.69981ms","start":"2026-02-05T17:37:01.81786Z","end":"2026-02-05T17:37:01.972559Z","steps":["trace[649048616] 'process raft request' (duration: 154.5996ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:01.994344Z","caller":"traceutil/trace.go:171","msg":"trace[303078789] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:581; }","duration":"134.406746ms","start":"2026-02-05T17:37:01.859922Z","end":"2026-02-05T17:37:01.994328Z","steps":["trace[303078789] 'read index received' (duration: 112.645879ms)","trace[303078789] 'applied index is now lower than readState.Index' (duration: 21.760027ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:37:01.994478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.583376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-02-05T17:37:01.994495Z","caller":"traceutil/trace.go:171","msg":"trace[2059502643] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:561; }","duration":"134.650426ms","start":"2026-02-05T17:37:01.859839Z","end":"2026-02-05T17:37:01.994489Z","steps":["trace[2059502643] 'agreement among raft nodes before linearized reading' (duration: 134.545536ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:01.994604Z","caller":"traceutil/trace.go:171","msg":"trace[120875932] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"161.535986ms","start":"2026-02-05T17:37:01.833062Z","end":"2026-02-05T17:37:01.994598Z","steps":["trace[120875932] 'process raft request' (duration: 160.993065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:02.862039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.591631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:37:02.862108Z","caller":"traceutil/trace.go:171","msg":"trace[1737341231] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:563; }","duration":"426.704271ms","start":"2026-02-05T17:37:02.435391Z","end":"2026-02-05T17:37:02.862095Z","steps":["trace[1737341231] 'range keys from in-memory index tree' (duration: 426.540681ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:02.862146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:02.435378Z","time spent":"426.763171ms","remote":"127.0.0.1:43860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T17:37:02.862229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.297644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-05T17:37:02.862276Z","caller":"traceutil/trace.go:171","msg":"trace[520680631] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:563; }","duration":"380.376324ms","start":"2026-02-05T17:37:02.481887Z","end":"2026-02-05T17:37:02.862263Z","steps":["trace[520680631] 'range keys from in-memory index tree' (duration: 380.185614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:02.862299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:02.481874Z","time spent":"380.420414ms","remote":"127.0.0.1:43892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5609,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-02-05T17:37:02.862455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.321874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T17:37:02.863417Z","caller":"traceutil/trace.go:171","msg":"trace[1927453691] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:584; }","duration":"204.754629ms","start":"2026-02-05T17:37:02.658655Z","end":"2026-02-05T17:37:02.86341Z","steps":["trace[1927453691] 'read index received' (duration: 204.563298ms)","trace[1927453691] 'applied index is now lower than readState.Index' (duration: 190.941µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:02.863609Z","caller":"traceutil/trace.go:171","msg":"trace[1962771743] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"380.798596ms","start":"2026-02-05T17:37:02.482805Z","end":"2026-02-05T17:37:02.863604Z","steps":["trace[1962771743] 'process raft request' (duration: 380.470985ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:02.863667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:02.482795Z","time spent":"380.833816ms","remote":"127.0.0.1:43892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:37:02.863778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.1231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-05T17:37:02.863813Z","caller":"traceutil/trace.go:171","msg":"trace[1905826687] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:564; }","duration":"205.1835ms","start":"2026-02-05T17:37:02.658624Z","end":"2026-02-05T17:37:02.863808Z","steps":["trace[1905826687] 'agreement among raft nodes before linearized reading' (duration: 205.11123ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:02.862473Z","caller":"traceutil/trace.go:171","msg":"trace[1579684752] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:563; }","duration":"481.375964ms","start":"2026-02-05T17:37:02.381093Z","end":"2026-02-05T17:37:02.862469Z","steps":["trace[1579684752] 'range keys from in-memory index tree' (duration: 481.203484ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:02.879707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:02.38108Z","time spent":"498.616758ms","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-05T17:37:05.266142Z","caller":"traceutil/trace.go:171","msg":"trace[1280364419] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"227.458107ms","start":"2026-02-05T17:37:05.038669Z","end":"2026-02-05T17:37:05.266127Z","steps":["trace[1280364419] 'process raft request' (duration: 227.366496ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:07.775358Z","caller":"traceutil/trace.go:171","msg":"trace[432584478] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"153.057467ms","start":"2026-02-05T17:37:07.622287Z","end":"2026-02-05T17:37:07.775345Z","steps":["trace[432584478] 'process raft request' (duration: 152.973187ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:07.902258Z","caller":"traceutil/trace.go:171","msg":"trace[1215966005] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"129.328703ms","start":"2026-02-05T17:37:07.77291Z","end":"2026-02-05T17:37:07.902239Z","steps":["trace[1215966005] 'read index received' (duration: 2.667445ms)","trace[1215966005] 'applied index is now lower than readState.Index' (duration: 126.660398ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:07.902512Z","caller":"traceutil/trace.go:171","msg":"trace[1645013260] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"279.155412ms","start":"2026-02-05T17:37:07.62335Z","end":"2026-02-05T17:37:07.902506Z","steps":["trace[1645013260] 'process raft request' (duration: 224.428512ms)","trace[1645013260] 'compare' (duration: 54.336428ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:37:07.902664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.743075ms","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-05T17:37:07.902681Z","caller":"traceutil/trace.go:171","msg":"trace[768965887] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:581; }","duration":"129.794637ms","start":"2026-02-05T17:37:07.772882Z","end":"2026-02-05T17:37:07.902676Z","steps":["trace[768965887] 'agreement among raft nodes before linearized reading' (duration: 129.719675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:07.902784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.338027ms","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-05T17:37:07.9028Z","caller":"traceutil/trace.go:171","msg":"trace[982640865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:581; }","duration":"126.379007ms","start":"2026-02-05T17:37:07.776415Z","end":"2026-02-05T17:37:07.902794Z","steps":["trace[982640865] 'agreement among raft nodes before linearized reading' (duration: 126.337357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:10.318251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.725773ms","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-05T17:37:10.318334Z","caller":"traceutil/trace.go:171","msg":"trace[1487195980] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:587; }","duration":"539.838423ms","start":"2026-02-05T17:37:09.778478Z","end":"2026-02-05T17:37:10.318316Z","steps":["trace[1487195980] 'range keys from in-memory index tree' (duration: 539.576552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:10.318377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:09.778465Z","time spent":"539.899984ms","remote":"127.0.0.1:44160","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-05T17:37:10.318688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.724603ms","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-05T17:37:10.31872Z","caller":"traceutil/trace.go:171","msg":"trace[1950197296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:587; }","duration":"412.796613ms","start":"2026-02-05T17:37:09.905914Z","end":"2026-02-05T17:37:10.318711Z","steps":["trace[1950197296] 'range keys from in-memory index tree' (duration: 412.607653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:10.318749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:09.905894Z","time spent":"412.848254ms","remote":"127.0.0.1:44160","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-05T17:37:10.318956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.145266ms","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-05T17:37:10.318986Z","caller":"traceutil/trace.go:171","msg":"trace[1924223330] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:587; }","duration":"410.203287ms","start":"2026-02-05T17:37:09.908772Z","end":"2026-02-05T17:37:10.318975Z","steps":["trace[1924223330] 'range keys from in-memory index tree' (duration: 410.043855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:10.319012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:09.908756Z","time spent":"410.250707ms","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T17:37:10.319218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.037805ms","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-05T17:37:10.319247Z","caller":"traceutil/trace.go:171","msg":"trace[652530733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:587; }","duration":"410.078466ms","start":"2026-02-05T17:37:09.90916Z","end":"2026-02-05T17:37:10.319239Z","steps":["trace[652530733] 'range keys from in-memory index tree' (duration: 409.966085ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:10.319276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:09.909118Z","time spent":"410.152656ms","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T17:37:10.319452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.810381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qv9lq\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-05T17:37:10.31948Z","caller":"traceutil/trace.go:171","msg":"trace[1841852461] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qv9lq; range_end:; response_count:1; response_revision:587; }","duration":"210.857501ms","start":"2026-02-05T17:37:10.108613Z","end":"2026-02-05T17:37:10.319471Z","steps":["trace[1841852461] 'range keys from in-memory index tree' (duration: 210.756381ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:10.541534Z","caller":"traceutil/trace.go:171","msg":"trace[63785718] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"208.827558ms","start":"2026-02-05T17:37:10.332687Z","end":"2026-02-05T17:37:10.541514Z","steps":["trace[63785718] 'process raft request' (duration: 208.728936ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:10.834734Z","caller":"traceutil/trace.go:171","msg":"trace[1832680400] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:611; }","duration":"432.156678ms","start":"2026-02-05T17:37:10.402547Z","end":"2026-02-05T17:37:10.834704Z","steps":["trace[1832680400] 'read index received' (duration: 139.355547ms)","trace[1832680400] 'applied index is now lower than readState.Index' (duration: 292.800051ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:37:10.834883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.322239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:37:10.834909Z","caller":"traceutil/trace.go:171","msg":"trace[139734081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"432.389839ms","start":"2026-02-05T17:37:10.40251Z","end":"2026-02-05T17:37:10.8349Z","steps":["trace[139734081] 'agreement among raft nodes before linearized reading' (duration: 432.290968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:10.834934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:10.402495Z","time spent":"432.433329ms","remote":"127.0.0.1:43860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T17:37:10.835229Z","caller":"traceutil/trace.go:171","msg":"trace[458056882] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"502.124182ms","start":"2026-02-05T17:37:10.333096Z","end":"2026-02-05T17:37:10.83522Z","steps":["trace[458056882] 'process raft request' (duration: 493.32262ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:10.835311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:10.333076Z","time spent":"502.174632ms","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:37:10.835406Z","caller":"traceutil/trace.go:171","msg":"trace[1208043442] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"502.249163ms","start":"2026-02-05T17:37:10.33315Z","end":"2026-02-05T17:37:10.8354Z","steps":["trace[1208043442] 'process raft request' (duration: 501.391638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:10.835442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:10.333147Z","time spent":"502.272833ms","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:37:10.835519Z","caller":"traceutil/trace.go:171","msg":"trace[293228999] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"502.334944ms","start":"2026-02-05T17:37:10.333179Z","end":"2026-02-05T17:37:10.835514Z","steps":["trace[293228999] 'process raft request' (duration: 501.396298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:10.835549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:10.333175Z","time spent":"502.356854ms","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:37:10.835714Z","caller":"traceutil/trace.go:171","msg":"trace[806102225] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"498.579727ms","start":"2026-02-05T17:37:10.337128Z","end":"2026-02-05T17:37:10.835708Z","steps":["trace[806102225] 'process raft request' (duration: 497.475699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:10.835747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:10.33712Z","time spent":"498.608288ms","remote":"127.0.0.1:44056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:37:14.009221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.706173477s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T17:37:14.009265Z","caller":"traceutil/trace.go:171","msg":"trace[249807079] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:592; }","duration":"2.706244087s","start":"2026-02-05T17:37:11.303011Z","end":"2026-02-05T17:37:14.009255Z","steps":["trace[249807079] 'range keys from in-memory index tree' (duration: 2.706051465s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:14.009287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:11.302997Z","time spent":"2.706284667s","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T17:37:14.009448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.461660635s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:37:14.009462Z","caller":"traceutil/trace.go:171","msg":"trace[807985108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"2.461696085s","start":"2026-02-05T17:37:11.547761Z","end":"2026-02-05T17:37:14.009457Z","steps":["trace[807985108] 'range keys from in-memory index tree' (duration: 2.461614074s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:14.009473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:11.547747Z","time spent":"2.461723435s","remote":"127.0.0.1:43860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T17:37:14.009806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.608156403s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:37:14.00982Z","caller":"traceutil/trace.go:171","msg":"trace[2008484508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"1.608194953s","start":"2026-02-05T17:37:12.40162Z","end":"2026-02-05T17:37:14.009815Z","steps":["trace[2008484508] 'range keys from in-memory index tree' (duration: 1.608111542s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:14.009832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:12.401601Z","time spent":"1.608227283s","remote":"127.0.0.1:43862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T17:37:14.009981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.465272473s","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-05T17:37:14.009995Z","caller":"traceutil/trace.go:171","msg":"trace[1784055069] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:592; }","duration":"1.465309843s","start":"2026-02-05T17:37:12.544681Z","end":"2026-02-05T17:37:14.00999Z","steps":["trace[1784055069] 'range keys from in-memory index tree' (duration: 1.465197422s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:14.010011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:12.544666Z","time spent":"1.465338814s","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T17:37:14.010145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.154935758s","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-05T17:37:14.010158Z","caller":"traceutil/trace.go:171","msg":"trace[1950903351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:592; }","duration":"1.154956069s","start":"2026-02-05T17:37:12.855198Z","end":"2026-02-05T17:37:14.010154Z","steps":["trace[1950903351] 'range keys from in-memory index tree' (duration: 1.154912868s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:14.010169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:12.855196Z","time spent":"1.154970889s","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T17:37:14.010223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.155057859s","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-05T17:37:14.010235Z","caller":"traceutil/trace.go:171","msg":"trace[324976887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:592; }","duration":"1.15506894s","start":"2026-02-05T17:37:12.855162Z","end":"2026-02-05T17:37:14.010231Z","steps":["trace[324976887] 'range keys from in-memory index tree' (duration: 1.154980758s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:14.010245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:12.855126Z","time spent":"1.15511672s","remote":"127.0.0.1:44160","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-05T17:37:14.010297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.154329513s","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-05T17:37:14.01031Z","caller":"traceutil/trace.go:171","msg":"trace[60665114] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:592; }","duration":"1.154364603s","start":"2026-02-05T17:37:12.855942Z","end":"2026-02-05T17:37:14.010306Z","steps":["trace[60665114] 'range keys from in-memory index tree' (duration: 1.154276622s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:14.010321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:12.855928Z","time spent":"1.154389913s","remote":"127.0.0.1:44160","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-05T17:37:14.20646Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125952537335248664,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T17:37:14.450818Z","caller":"traceutil/trace.go:171","msg":"trace[1761038210] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"898.057343ms","start":"2026-02-05T17:37:13.552739Z","end":"2026-02-05T17:37:14.450797Z","steps":["trace[1761038210] 'process raft request' (duration: 897.902942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:14.450998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:13.552711Z","time spent":"898.224005ms","remote":"127.0.0.1:43924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T17:37:14.631097Z","caller":"traceutil/trace.go:171","msg":"trace[686389400] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:616; }","duration":"924.688386ms","start":"2026-02-05T17:37:13.706361Z","end":"2026-02-05T17:37:14.63105Z","steps":["trace[686389400] 'read index received' (duration: 745.101916ms)","trace[686389400] 'applied index is now lower than readState.Index' (duration: 179.58384ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:14.631226Z","caller":"traceutil/trace.go:171","msg":"trace[1262263486] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"986.240634ms","start":"2026-02-05T17:37:13.64498Z","end":"2026-02-05T17:37:14.631221Z","steps":["trace[1262263486] 'process raft request' (duration: 985.914721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:14.631288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:13.644956Z","time spent":"986.286044ms","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:37:14.631373Z","caller":"traceutil/trace.go:171","msg":"trace[1834077754] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"610.852477ms","start":"2026-02-05T17:37:14.020517Z","end":"2026-02-05T17:37:14.631369Z","steps":["trace[1834077754] 'process raft request' (duration: 610.446804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:14.631396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:14.0205Z","time spent":"610.883758ms","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:37:14.63148Z","caller":"traceutil/trace.go:171","msg":"trace[1898253090] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"610.895649ms","start":"2026-02-05T17:37:14.020576Z","end":"2026-02-05T17:37:14.631472Z","steps":["trace[1898253090] 'process raft request' (duration: 610.405324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:14.631509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:14.020572Z","time spent":"610.922129ms","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:37:14.631567Z","caller":"traceutil/trace.go:171","msg":"trace[679833825] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"610.95684ms","start":"2026-02-05T17:37:14.020606Z","end":"2026-02-05T17:37:14.631563Z","steps":["trace[679833825] 'process raft request' (duration: 610.391124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:14.631591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:14.020602Z","time spent":"610.97638ms","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:37:14.631646Z","caller":"traceutil/trace.go:171","msg":"trace[1646857324] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"605.129257ms","start":"2026-02-05T17:37:14.026513Z","end":"2026-02-05T17:37:14.631642Z","steps":["trace[1646857324] 'process raft request' (duration: 604.50296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:14.631675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:14.026475Z","time spent":"605.187917ms","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:37:14.631726Z","caller":"traceutil/trace.go:171","msg":"trace[910943718] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"603.116389ms","start":"2026-02-05T17:37:14.028605Z","end":"2026-02-05T17:37:14.631722Z","steps":["trace[910943718] 'process raft request' (duration: 602.424743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:14.631747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:14.028599Z","time spent":"603.135849ms","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:37:14.631862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.500994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.23\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-05T17:37:14.631881Z","caller":"traceutil/trace.go:171","msg":"trace[1996412866] range","detail":"{range_begin:/registry/masterleases/199.204.45.23; range_end:; response_count:1; response_revision:599; }","duration":"925.547284ms","start":"2026-02-05T17:37:13.706329Z","end":"2026-02-05T17:37:14.631876Z","steps":["trace[1996412866] 'agreement among raft nodes before linearized reading' (duration: 925.462503ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:14.631895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:13.706315Z","time spent":"925.577394ms","remote":"127.0.0.1:43880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.23\" "} {"level":"warn","ts":"2026-02-05T17:37:14.631984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.734814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:37:14.631999Z","caller":"traceutil/trace.go:171","msg":"trace[883249293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"604.771794ms","start":"2026-02-05T17:37:14.027223Z","end":"2026-02-05T17:37:14.631994Z","steps":["trace[883249293] 'agreement among raft nodes before linearized reading' (duration: 604.738893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:14.632014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:14.027215Z","time spent":"604.795044ms","remote":"127.0.0.1:43860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T17:37:17.401655Z","caller":"traceutil/trace.go:171","msg":"trace[437551705] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"205.421857ms","start":"2026-02-05T17:37:17.196217Z","end":"2026-02-05T17:37:17.401639Z","steps":["trace[437551705] 'process raft request' (duration: 137.490161ms)","trace[437551705] 'compare' (duration: 67.628833ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:18.751429Z","caller":"traceutil/trace.go:171","msg":"trace[1332929509] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"106.595731ms","start":"2026-02-05T17:37:18.64481Z","end":"2026-02-05T17:37:18.751406Z","steps":["trace[1332929509] 'read index received' (duration: 60.298345ms)","trace[1332929509] 'applied index is now lower than readState.Index' (duration: 46.296416ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:18.751556Z","caller":"traceutil/trace.go:171","msg":"trace[1745739096] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"186.104468ms","start":"2026-02-05T17:37:18.565442Z","end":"2026-02-05T17:37:18.751546Z","steps":["trace[1745739096] 'process raft request' (duration: 139.734341ms)","trace[1745739096] 'compare' (duration: 45.951703ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:37:18.751855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.033786ms","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-05T17:37:18.751914Z","caller":"traceutil/trace.go:171","msg":"trace[1127983506] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:622; }","duration":"107.132716ms","start":"2026-02-05T17:37:18.644769Z","end":"2026-02-05T17:37:18.751902Z","steps":["trace[1127983506] 'agreement among raft nodes before linearized reading' (duration: 106.950315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:18.752111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.297365ms","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-05T17:37:18.752144Z","caller":"traceutil/trace.go:171","msg":"trace[178462894] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:622; }","duration":"102.375667ms","start":"2026-02-05T17:37:18.649761Z","end":"2026-02-05T17:37:18.752137Z","steps":["trace[178462894] 'agreement among raft nodes before linearized reading' (duration: 102.292346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:18.752592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.719301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T17:37:18.752627Z","caller":"traceutil/trace.go:171","msg":"trace[1510918216] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:622; }","duration":"101.797302ms","start":"2026-02-05T17:37:18.650822Z","end":"2026-02-05T17:37:18.752619Z","steps":["trace[1510918216] 'agreement among raft nodes before linearized reading' (duration: 101.724731ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:19.150131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.144511ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:37:19.156778Z","caller":"traceutil/trace.go:171","msg":"trace[81228613] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"394.802293ms","start":"2026-02-05T17:37:18.761949Z","end":"2026-02-05T17:37:19.156752Z","steps":["trace[81228613] 'process raft request' (duration: 388.298882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:19.157619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:18.761945Z","time spent":"395.49094ms","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:37:19.161713Z","caller":"traceutil/trace.go:171","msg":"trace[1206591459] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"399.785788ms","start":"2026-02-05T17:37:18.761912Z","end":"2026-02-05T17:37:19.161698Z","steps":["trace[1206591459] 'process raft request' (duration: 132.930788ms)","trace[1206591459] 'compare' (duration: 254.049699ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:37:19.16177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:18.761906Z","time spent":"399.834288ms","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:37:19.162876Z","caller":"traceutil/trace.go:171","msg":"trace[538382666] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"400.881209ms","start":"2026-02-05T17:37:18.761984Z","end":"2026-02-05T17:37:19.162865Z","steps":["trace[538382666] 'process raft request' (duration: 388.321642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:19.162928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:18.76198Z","time spent":"400.92064ms","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:37:19.163107Z","caller":"traceutil/trace.go:171","msg":"trace[801315599] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"401.080762ms","start":"2026-02-05T17:37:18.762016Z","end":"2026-02-05T17:37:19.163097Z","steps":["trace[801315599] 'process raft request' (duration: 392.813911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:19.163144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:18.762013Z","time spent":"401.111682ms","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:37:22.48934Z","caller":"traceutil/trace.go:171","msg":"trace[1224791057] transaction","detail":"{read_only:false; number_of_response:0; response_revision:646; }","duration":"187.890182ms","start":"2026-02-05T17:37:22.301425Z","end":"2026-02-05T17:37:22.489315Z","steps":["trace[1224791057] 'process raft request' (duration: 118.993398ms)","trace[1224791057] 'compare' (duration: 68.853543ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:22.493822Z","caller":"traceutil/trace.go:171","msg":"trace[1300200627] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"179.641421ms","start":"2026-02-05T17:37:22.314154Z","end":"2026-02-05T17:37:22.493795Z","steps":["trace[1300200627] 'process raft request' (duration: 179.406578ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:23.405952Z","caller":"traceutil/trace.go:171","msg":"trace[1261965121] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"139.710412ms","start":"2026-02-05T17:37:23.266226Z","end":"2026-02-05T17:37:23.405936Z","steps":["trace[1261965121] 'process raft request' (duration: 139.391398ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:35.812731Z","caller":"traceutil/trace.go:171","msg":"trace[585436114] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:791; }","duration":"310.210564ms","start":"2026-02-05T17:37:35.502503Z","end":"2026-02-05T17:37:35.812714Z","steps":["trace[585436114] 'read index received' (duration: 249.246665ms)","trace[585436114] 'applied index is now lower than readState.Index' (duration: 60.963259ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:37:35.812901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.382317ms","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-05T17:37:35.812936Z","caller":"traceutil/trace.go:171","msg":"trace[2016261611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:763; }","duration":"310.427758ms","start":"2026-02-05T17:37:35.502498Z","end":"2026-02-05T17:37:35.812926Z","steps":["trace[2016261611] 'agreement among raft nodes before linearized reading' (duration: 310.281895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:35.812959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:35.50246Z","time spent":"310.491848ms","remote":"127.0.0.1:44160","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":"info","ts":"2026-02-05T17:37:35.812992Z","caller":"traceutil/trace.go:171","msg":"trace[2046798054] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"351.534098ms","start":"2026-02-05T17:37:35.461445Z","end":"2026-02-05T17:37:35.812979Z","steps":["trace[2046798054] 'process raft request' (duration: 290.295725ms)","trace[2046798054] 'compare' (duration: 60.861057ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:37:35.813087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:35.461426Z","time spent":"351.590109ms","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:37:35.813096Z","caller":"traceutil/trace.go:171","msg":"trace[1549956573] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"349.90742ms","start":"2026-02-05T17:37:35.463181Z","end":"2026-02-05T17:37:35.813088Z","steps":["trace[1549956573] 'process raft request' (duration: 349.506994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:35.813148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:35.463167Z","time spent":"349.957472ms","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:37:49.966406Z","caller":"traceutil/trace.go:171","msg":"trace[1883043780] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"102.519115ms","start":"2026-02-05T17:37:49.863869Z","end":"2026-02-05T17:37:49.966388Z","steps":["trace[1883043780] 'process raft request' (duration: 102.088116ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:50.148569Z","caller":"traceutil/trace.go:171","msg":"trace[1077336534] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"177.429251ms","start":"2026-02-05T17:37:49.971124Z","end":"2026-02-05T17:37:50.148554Z","steps":["trace[1077336534] 'process raft request' (duration: 125.000946ms)","trace[1077336534] 'compare' (duration: 52.063097ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:50.149254Z","caller":"traceutil/trace.go:171","msg":"trace[1151994844] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"177.074344ms","start":"2026-02-05T17:37:49.972168Z","end":"2026-02-05T17:37:50.149242Z","steps":["trace[1151994844] 'process raft request' (duration: 176.095994ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:06.23573Z","caller":"traceutil/trace.go:171","msg":"trace[1262561739] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"148.177716ms","start":"2026-02-05T17:38:06.087532Z","end":"2026-02-05T17:38:06.23571Z","steps":["trace[1262561739] 'process raft request' (duration: 148.056433ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:08.614284Z","caller":"traceutil/trace.go:171","msg":"trace[860798384] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"127.122086ms","start":"2026-02-05T17:38:08.487148Z","end":"2026-02-05T17:38:08.61427Z","steps":["trace[860798384] 'process raft request' (duration: 127.098385ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:08.614612Z","caller":"traceutil/trace.go:171","msg":"trace[1265360705] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"156.649709ms","start":"2026-02-05T17:38:08.457956Z","end":"2026-02-05T17:38:08.614606Z","steps":["trace[1265360705] 'process raft request' (duration: 95.143012ms)","trace[1265360705] 'compare' (duration: 61.026575ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:38:19.923248Z","caller":"traceutil/trace.go:171","msg":"trace[1983182417] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"145.298791ms","start":"2026-02-05T17:38:19.777927Z","end":"2026-02-05T17:38:19.923226Z","steps":["trace[1983182417] 'process raft request' (duration: 107.546985ms)","trace[1983182417] 'compare' (duration: 37.632773ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:38:29.127042Z","caller":"traceutil/trace.go:171","msg":"trace[657406806] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"191.984447ms","start":"2026-02-05T17:38:28.935033Z","end":"2026-02-05T17:38:29.127017Z","steps":["trace[657406806] 'process raft request' (duration: 191.899984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:35.942734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.416756ms","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-05T17:38:35.942802Z","caller":"traceutil/trace.go:171","msg":"trace[1504433564] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:971; }","duration":"107.52491ms","start":"2026-02-05T17:38:35.835262Z","end":"2026-02-05T17:38:35.942787Z","steps":["trace[1504433564] 'range keys from in-memory index tree' (duration: 107.270502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:35.943128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.800937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:38:35.943156Z","caller":"traceutil/trace.go:171","msg":"trace[1194047448] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:971; }","duration":"190.855189ms","start":"2026-02-05T17:38:35.752291Z","end":"2026-02-05T17:38:35.943146Z","steps":["trace[1194047448] 'range keys from in-memory index tree' (duration: 190.742156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:44.971954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.605821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-proxier\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-05T17:38:44.972721Z","caller":"traceutil/trace.go:171","msg":"trace[1427083228] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-proxier; range_end:; response_count:1; response_revision:1001; }","duration":"109.403545ms","start":"2026-02-05T17:38:44.863301Z","end":"2026-02-05T17:38:44.972705Z","steps":["trace[1427083228] 'range keys from in-memory index tree' (duration: 108.477667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:07.22351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.888517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pvc-protection-controller\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-05T17:39:07.223593Z","caller":"traceutil/trace.go:171","msg":"trace[635085028] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pvc-protection-controller; range_end:; response_count:1; response_revision:1063; }","duration":"403.018711ms","start":"2026-02-05T17:39:06.820556Z","end":"2026-02-05T17:39:07.223574Z","steps":["trace[635085028] 'range keys from in-memory index tree' (duration: 402.814645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:07.223686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:06.820542Z","time spent":"403.132895ms","remote":"127.0.0.1:44082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/pvc-protection-controller\" "} {"level":"warn","ts":"2026-02-05T17:39:07.224319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.508962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:76 size:56302"} {"level":"info","ts":"2026-02-05T17:39:07.224365Z","caller":"traceutil/trace.go:171","msg":"trace[134193930] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:76; response_revision:1063; }","duration":"400.557393ms","start":"2026-02-05T17:39:06.823796Z","end":"2026-02-05T17:39:07.224353Z","steps":["trace[134193930] 'range keys from in-memory index tree' (duration: 400.18116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:07.224493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:06.82378Z","time spent":"400.669177ms","remote":"127.0.0.1:43924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":76,"response size":56325,"request content":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 "} {"level":"warn","ts":"2026-02-05T17:39:07.223533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.703006ms","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-05T17:39:07.224921Z","caller":"traceutil/trace.go:171","msg":"trace[4675328] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1063; }","duration":"280.10214ms","start":"2026-02-05T17:39:06.944798Z","end":"2026-02-05T17:39:07.2249Z","steps":["trace[4675328] 'range keys from in-memory index tree' (duration: 278.489328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:09.004971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.827214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/local-path-storage/local-path-config\" ","response":"range_response_count:1 size:1442"} {"level":"info","ts":"2026-02-05T17:39:09.00561Z","caller":"traceutil/trace.go:171","msg":"trace[219062219] range","detail":"{range_begin:/registry/configmaps/local-path-storage/local-path-config; range_end:; response_count:1; response_revision:1068; }","duration":"158.532697ms","start":"2026-02-05T17:39:08.847056Z","end":"2026-02-05T17:39:09.005588Z","steps":["trace[219062219] 'range keys from in-memory index tree' (duration: 157.737952ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:09.005001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.977341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:3717"} {"level":"info","ts":"2026-02-05T17:39:09.005933Z","caller":"traceutil/trace.go:171","msg":"trace[1537329596] range","detail":"{range_begin:/registry/deployments/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:1068; }","duration":"105.911141ms","start":"2026-02-05T17:39:08.89997Z","end":"2026-02-05T17:39:09.005882Z","steps":["trace[1537329596] 'range keys from in-memory index tree' (duration: 104.809117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:09.229522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.304805ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-05T17:39:09.229833Z","caller":"traceutil/trace.go:171","msg":"trace[1099239341] linearizableReadLoop","detail":"{readStateIndex:1121; appliedIndex:1120; }","duration":"140.905958ms","start":"2026-02-05T17:39:09.088869Z","end":"2026-02-05T17:39:09.229775Z","steps":["trace[1099239341] 'read index received' (duration: 2.293342ms)","trace[1099239341] 'applied index is now lower than readState.Index' (duration: 138.609996ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:09.230292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.417556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:3717"} {"level":"info","ts":"2026-02-05T17:39:09.230359Z","caller":"traceutil/trace.go:171","msg":"trace[802589502] range","detail":"{range_begin:/registry/deployments/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:1068; }","duration":"141.513909ms","start":"2026-02-05T17:39:09.08883Z","end":"2026-02-05T17:39:09.230344Z","steps":["trace[802589502] 'agreement among raft nodes before linearized reading' (duration: 141.344814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:09.230767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.710085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/local-path-storage/local-path-config\" ","response":"range_response_count:1 size:1442"} {"level":"info","ts":"2026-02-05T17:39:09.230806Z","caller":"traceutil/trace.go:171","msg":"trace[1323507437] range","detail":"{range_begin:/registry/configmaps/local-path-storage/local-path-config; range_end:; response_count:1; response_revision:1068; }","duration":"135.750326ms","start":"2026-02-05T17:39:09.095039Z","end":"2026-02-05T17:39:09.23079Z","steps":["trace[1323507437] 'agreement among raft nodes before linearized reading' (duration: 135.143557ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:11.833745Z","caller":"traceutil/trace.go:171","msg":"trace[2103039080] linearizableReadLoop","detail":"{readStateIndex:1130; appliedIndex:1129; }","duration":"110.846411ms","start":"2026-02-05T17:39:11.722876Z","end":"2026-02-05T17:39:11.833723Z","steps":["trace[2103039080] 'read index received' (duration: 110.590083ms)","trace[2103039080] 'applied index is now lower than readState.Index' (duration: 255.268µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:11.834154Z","caller":"traceutil/trace.go:171","msg":"trace[735318345] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"141.133895ms","start":"2026-02-05T17:39:11.693005Z","end":"2026-02-05T17:39:11.834139Z","steps":["trace[735318345] 'process raft request' (duration: 140.542366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:11.834987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.087911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-n99x9\" ","response":"range_response_count:1 size:4624"} {"level":"info","ts":"2026-02-05T17:39:11.835681Z","caller":"traceutil/trace.go:171","msg":"trace[1886022286] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-n99x9; range_end:; response_count:1; response_revision:1077; }","duration":"112.812324ms","start":"2026-02-05T17:39:11.722843Z","end":"2026-02-05T17:39:11.835655Z","steps":["trace[1886022286] 'agreement among raft nodes before linearized reading' (duration: 112.047529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:12.045363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.299646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-n99x9\" ","response":"range_response_count:1 size:4624"} {"level":"info","ts":"2026-02-05T17:39:12.045475Z","caller":"traceutil/trace.go:171","msg":"trace[816569392] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-n99x9; range_end:; response_count:1; response_revision:1077; }","duration":"126.44018ms","start":"2026-02-05T17:39:11.919004Z","end":"2026-02-05T17:39:12.045445Z","steps":["trace[816569392] 'range keys from in-memory index tree' (duration: 126.12146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:14.683434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.625532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5088"} {"level":"info","ts":"2026-02-05T17:39:14.683512Z","caller":"traceutil/trace.go:171","msg":"trace[373115422] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:1084; }","duration":"147.735056ms","start":"2026-02-05T17:39:14.535759Z","end":"2026-02-05T17:39:14.683494Z","steps":["trace[373115422] 'range keys from in-memory index tree' (duration: 147.463067ms)"],"step_count":1}