{"level":"warn","ts":"2026-02-04T05:14:29.205246Z","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-04T05:14:29.20534Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.41: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.41:2380","--initial-cluster=instance=https://199.204.45.41:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.41:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.41: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-04T05:14:29.20542Z","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-04T05:14:29.205433Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.41:2380"]} {"level":"info","ts":"2026-02-04T05:14:29.205452Z","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-04T05:14:29.206111Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"]} {"level":"info","ts":"2026-02-04T05:14:29.206203Z","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.41:2380"],"listen-peer-urls":["https://199.204.45.41:2380"],"advertise-client-urls":["https://199.204.45.41:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.41: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-04T05:14:29.212485Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.026265ms"} {"level":"info","ts":"2026-02-04T05:14:29.218471Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"17dd22a2661aae79","cluster-id":"c5133f40b27eeb3"} {"level":"info","ts":"2026-02-04T05:14:29.218535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=()"} {"level":"info","ts":"2026-02-04T05:14:29.218557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became follower at term 0"} {"level":"info","ts":"2026-02-04T05:14:29.218584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 17dd22a2661aae79 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-04T05:14:29.218592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became follower at term 1"} {"level":"info","ts":"2026-02-04T05:14:29.218619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=(1719568713618468473)"} {"level":"warn","ts":"2026-02-04T05:14:29.225849Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-04T05:14:29.232587Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-04T05:14:29.237437Z","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-04T05:14:29.240206Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"17dd22a2661aae79","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-04T05:14:29.241637Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"17dd22a2661aae79","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-04T05:14:29.241725Z","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-04T05:14:29.241749Z","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-04T05:14:29.241762Z","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-04T05:14:29.242323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=(1719568713618468473)"} {"level":"info","ts":"2026-02-04T05:14:29.242391Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c5133f40b27eeb3","local-member-id":"17dd22a2661aae79","added-peer-id":"17dd22a2661aae79","added-peer-peer-urls":["https://199.204.45.41:2380"]} {"level":"info","ts":"2026-02-04T05:14:29.243041Z","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-04T05:14:29.243132Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.41:2380"} {"level":"info","ts":"2026-02-04T05:14:29.243138Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.41:2380"} {"level":"info","ts":"2026-02-04T05:14:29.244087Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"17dd22a2661aae79","initial-advertise-peer-urls":["https://199.204.45.41:2380"],"listen-peer-urls":["https://199.204.45.41:2380"],"advertise-client-urls":["https://199.204.45.41:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-04T05:14:29.244109Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-04T05:14:29.619339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 is starting a new election at term 1"} {"level":"info","ts":"2026-02-04T05:14:29.619375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-04T05:14:29.619388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 received MsgPreVoteResp from 17dd22a2661aae79 at term 1"} {"level":"info","ts":"2026-02-04T05:14:29.619399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became candidate at term 2"} {"level":"info","ts":"2026-02-04T05:14:29.619439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 received MsgVoteResp from 17dd22a2661aae79 at term 2"} {"level":"info","ts":"2026-02-04T05:14:29.619463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became leader at term 2"} {"level":"info","ts":"2026-02-04T05:14:29.619472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 17dd22a2661aae79 elected leader 17dd22a2661aae79 at term 2"} {"level":"info","ts":"2026-02-04T05:14:29.621782Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"17dd22a2661aae79","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.41:2379]}","request-path":"/0/members/17dd22a2661aae79/attributes","cluster-id":"c5133f40b27eeb3","publish-timeout":"7s"} {"level":"info","ts":"2026-02-04T05:14:29.621807Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T05:14:29.622103Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T05:14:29.622684Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T05:14:29.62679Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-04T05:14:29.626898Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c5133f40b27eeb3","local-member-id":"17dd22a2661aae79","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T05:14:29.627105Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T05:14:29.627123Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T05:14:29.630277Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-04T05:14:29.630356Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-04T05:14:29.639787Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.41:2379"} {"level":"info","ts":"2026-02-04T05:14:35.589218Z","caller":"traceutil/trace.go:171","msg":"trace[423129152] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"110.741547ms","start":"2026-02-04T05:14:35.478443Z","end":"2026-02-04T05:14:35.589184Z","steps":["trace[423129152] 'process raft request' (duration: 110.688707ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:35.589716Z","caller":"traceutil/trace.go:171","msg":"trace[1722860251] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"127.443113ms","start":"2026-02-04T05:14:35.462259Z","end":"2026-02-04T05:14:35.589702Z","steps":["trace[1722860251] 'process raft request' (duration: 38.760367ms)","trace[1722860251] 'compare' (duration: 87.412994ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:14:35.58987Z","caller":"traceutil/trace.go:171","msg":"trace[681649420] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"127.450723ms","start":"2026-02-04T05:14:35.462409Z","end":"2026-02-04T05:14:35.58986Z","steps":["trace[681649420] 'process raft request' (duration: 126.616445ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:35.590902Z","caller":"traceutil/trace.go:171","msg":"trace[795131957] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"123.283072ms","start":"2026-02-04T05:14:35.467603Z","end":"2026-02-04T05:14:35.590886Z","steps":["trace[795131957] 'process raft request' (duration: 121.474634ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:39.175346Z","caller":"traceutil/trace.go:171","msg":"trace[2081142773] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"120.929813ms","start":"2026-02-04T05:14:39.054392Z","end":"2026-02-04T05:14:39.175322Z","steps":["trace[2081142773] 'process raft request' (duration: 59.217545ms)","trace[2081142773] 'compare' (duration: 61.564807ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:14:39.38402Z","caller":"traceutil/trace.go:171","msg":"trace[529241216] linearizableReadLoop","detail":"{readStateIndex:312; appliedIndex:311; }","duration":"105.547706ms","start":"2026-02-04T05:14:39.278458Z","end":"2026-02-04T05:14:39.384006Z","steps":["trace[529241216] 'read index received' (duration: 105.390485ms)","trace[529241216] 'applied index is now lower than readState.Index' (duration: 156.721µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:14:39.38421Z","caller":"traceutil/trace.go:171","msg":"trace[1411337047] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"146.603808ms","start":"2026-02-04T05:14:39.2376Z","end":"2026-02-04T05:14:39.384204Z","steps":["trace[1411337047] 'process raft request' (duration: 146.290395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:39.384337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.86749ms","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-04T05:14:39.384354Z","caller":"traceutil/trace.go:171","msg":"trace[868665227] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:302; }","duration":"105.927491ms","start":"2026-02-04T05:14:39.278421Z","end":"2026-02-04T05:14:39.384349Z","steps":["trace[868665227] 'agreement among raft nodes before linearized reading' (duration: 105.88311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:45.050347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.472514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4018"} {"level":"info","ts":"2026-02-04T05:14:45.050603Z","caller":"traceutil/trace.go:171","msg":"trace[349665181] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:367; }","duration":"488.760028ms","start":"2026-02-04T05:14:44.561825Z","end":"2026-02-04T05:14:45.050585Z","steps":["trace[349665181] 'range keys from in-memory index tree' (duration: 488.369934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:45.050726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:44.561809Z","time spent":"488.901669ms","remote":"127.0.0.1:56570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4040,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-04T05:14:45.050749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.726521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-04T05:14:45.050982Z","caller":"traceutil/trace.go:171","msg":"trace[1190882430] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:367; }","duration":"129.001283ms","start":"2026-02-04T05:14:44.921959Z","end":"2026-02-04T05:14:45.05096Z","steps":["trace[1190882430] 'range keys from in-memory index tree' (duration: 128.64556ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:45.421279Z","caller":"traceutil/trace.go:171","msg":"trace[471871237] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:379; }","duration":"195.483871ms","start":"2026-02-04T05:14:45.225752Z","end":"2026-02-04T05:14:45.421236Z","steps":["trace[471871237] 'read index received' (duration: 195.311059ms)","trace[471871237] 'applied index is now lower than readState.Index' (duration: 171.802µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:45.421409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.640103ms","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-04T05:14:45.421442Z","caller":"traceutil/trace.go:171","msg":"trace[495244382] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:368; }","duration":"195.710873ms","start":"2026-02-04T05:14:45.22572Z","end":"2026-02-04T05:14:45.421431Z","steps":["trace[495244382] 'agreement among raft nodes before linearized reading' (duration: 195.619852ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:45.421763Z","caller":"traceutil/trace.go:171","msg":"trace[1636592839] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"365.961639ms","start":"2026-02-04T05:14:45.055788Z","end":"2026-02-04T05:14:45.42175Z","steps":["trace[1636592839] 'process raft request' (duration: 365.335193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:45.422251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:45.055601Z","time spent":"366.18822ms","remote":"127.0.0.1:56670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:14:45.531355Z","caller":"traceutil/trace.go:171","msg":"trace[1134695320] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"104.620677ms","start":"2026-02-04T05:14:45.426706Z","end":"2026-02-04T05:14:45.531327Z","steps":["trace[1134695320] 'process raft request' (duration: 49.073729ms)","trace[1134695320] 'compare' (duration: 55.424077ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:46.373198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.088595ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:14:46.373341Z","caller":"traceutil/trace.go:171","msg":"trace[100880858] linearizableReadLoop","detail":"{readStateIndex:393; appliedIndex:389; }","duration":"414.375058ms","start":"2026-02-04T05:14:45.958957Z","end":"2026-02-04T05:14:46.373332Z","steps":["trace[100880858] 'read index received' (duration: 98.992711ms)","trace[100880858] 'applied index is now lower than readState.Index' (duration: 315.381717ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:46.373393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.431588ms","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-04T05:14:46.373405Z","caller":"traceutil/trace.go:171","msg":"trace[106969359] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-77cccfdc44; range_end:; response_count:1; response_revision:381; }","duration":"414.467431ms","start":"2026-02-04T05:14:45.958933Z","end":"2026-02-04T05:14:46.3734Z","steps":["trace[106969359] 'agreement among raft nodes before linearized reading' (duration: 414.4236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:46.373422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:45.958922Z","time spent":"414.495511ms","remote":"127.0.0.1:56888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":3810,"request content":"key:\"/registry/replicasets/kube-system/coredns-77cccfdc44\" "} {"level":"info","ts":"2026-02-04T05:14:46.373515Z","caller":"traceutil/trace.go:171","msg":"trace[1997703905] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"424.300387ms","start":"2026-02-04T05:14:45.949208Z","end":"2026-02-04T05:14:46.373508Z","steps":["trace[1997703905] 'process raft request' (duration: 108.780179ms)","trace[1997703905] 'compare' (duration: 314.658961ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:46.373538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:45.949195Z","time spent":"424.331499ms","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T05:14:46.373659Z","caller":"traceutil/trace.go:171","msg":"trace[712372921] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"424.332179ms","start":"2026-02-04T05:14:45.949322Z","end":"2026-02-04T05:14:46.373654Z","steps":["trace[712372921] 'process raft request' (duration: 423.936054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:46.373687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:45.949316Z","time spent":"424.35322ms","remote":"127.0.0.1:56580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4015,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:14:46.373743Z","caller":"traceutil/trace.go:171","msg":"trace[1305378212] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"424.376209ms","start":"2026-02-04T05:14:45.949363Z","end":"2026-02-04T05:14:46.373739Z","steps":["trace[1305378212] 'process raft request' (duration: 423.929835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:46.37376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:45.949359Z","time spent":"424.392949ms","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T05:14:46.37384Z","caller":"traceutil/trace.go:171","msg":"trace[131702400] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"422.727084ms","start":"2026-02-04T05:14:45.951108Z","end":"2026-02-04T05:14:46.373835Z","steps":["trace[131702400] 'process raft request' (duration: 422.199279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:46.37389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:45.951101Z","time spent":"422.748054ms","remote":"127.0.0.1:56888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3782,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:14:46.571255Z","caller":"traceutil/trace.go:171","msg":"trace[382004186] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"114.835549ms","start":"2026-02-04T05:14:46.45639Z","end":"2026-02-04T05:14:46.571226Z","steps":["trace[382004186] 'process raft request' (duration: 42.122774ms)","trace[382004186] 'compare' (duration: 71.738547ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:14:46.571726Z","caller":"traceutil/trace.go:171","msg":"trace[432568535] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"115.209242ms","start":"2026-02-04T05:14:46.456505Z","end":"2026-02-04T05:14:46.571714Z","steps":["trace[432568535] 'process raft request' (duration: 114.051012ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:46.763103Z","caller":"traceutil/trace.go:171","msg":"trace[1524187636] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"115.038151ms","start":"2026-02-04T05:14:46.648047Z","end":"2026-02-04T05:14:46.763085Z","steps":["trace[1524187636] 'process raft request' (duration: 114.961041ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:51.85233Z","caller":"traceutil/trace.go:171","msg":"trace[1029619471] linearizableReadLoop","detail":"{readStateIndex:432; appliedIndex:431; }","duration":"120.048608ms","start":"2026-02-04T05:14:51.73225Z","end":"2026-02-04T05:14:51.852298Z","steps":["trace[1029619471] 'read index received' (duration: 119.841216ms)","trace[1029619471] 'applied index is now lower than readState.Index' (duration: 206.252µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:14:51.852718Z","caller":"traceutil/trace.go:171","msg":"trace[2063388483] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"147.993744ms","start":"2026-02-04T05:14:51.704711Z","end":"2026-02-04T05:14:51.852705Z","steps":["trace[2063388483] 'process raft request' (duration: 147.4426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:51.852927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.662433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:14:51.852966Z","caller":"traceutil/trace.go:171","msg":"trace[46992072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:418; }","duration":"120.710714ms","start":"2026-02-04T05:14:51.732245Z","end":"2026-02-04T05:14:51.852956Z","steps":["trace[46992072] 'agreement among raft nodes before linearized reading' (duration: 120.640232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:51.853124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.264875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-04T05:14:51.85316Z","caller":"traceutil/trace.go:171","msg":"trace[743716097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:418; }","duration":"110.314216ms","start":"2026-02-04T05:14:51.742836Z","end":"2026-02-04T05:14:51.85315Z","steps":["trace[743716097] 'agreement among raft nodes before linearized reading' (duration: 110.259635ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:01.899631Z","caller":"traceutil/trace.go:171","msg":"trace[1133322700] linearizableReadLoop","detail":"{readStateIndex:518; appliedIndex:517; }","duration":"288.099673ms","start":"2026-02-04T05:15:01.611492Z","end":"2026-02-04T05:15:01.899591Z","steps":["trace[1133322700] 'read index received' (duration: 287.117715ms)","trace[1133322700] 'applied index is now lower than readState.Index' (duration: 929.347µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:15:01.900902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.398154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-04T05:15:01.900962Z","caller":"traceutil/trace.go:171","msg":"trace[533232813] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:501; }","duration":"289.479034ms","start":"2026-02-04T05:15:01.611465Z","end":"2026-02-04T05:15:01.900944Z","steps":["trace[533232813] 'agreement among raft nodes before linearized reading' (duration: 288.754819ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:01.901533Z","caller":"traceutil/trace.go:171","msg":"trace[1545321208] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"331.436294ms","start":"2026-02-04T05:15:01.570086Z","end":"2026-02-04T05:15:01.901522Z","steps":["trace[1545321208] 'process raft request' (duration: 328.727683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:01.901597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:01.57007Z","time spent":"331.483755ms","remote":"127.0.0.1:56454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24229,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:15:01.906878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.126327ms","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-04T05:15:01.906998Z","caller":"traceutil/trace.go:171","msg":"trace[209652417] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:501; }","duration":"258.268789ms","start":"2026-02-04T05:15:01.648715Z","end":"2026-02-04T05:15:01.906984Z","steps":["trace[209652417] 'agreement among raft nodes before linearized reading' (duration: 258.119717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:01.908033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.467413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:15:01.908089Z","caller":"traceutil/trace.go:171","msg":"trace[1034362888] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:501; }","duration":"173.543123ms","start":"2026-02-04T05:15:01.734539Z","end":"2026-02-04T05:15:01.908082Z","steps":["trace[1034362888] 'agreement among raft nodes before linearized reading' (duration: 173.324812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:01.908498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.477427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:15:01.908555Z","caller":"traceutil/trace.go:171","msg":"trace[2068855312] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:501; }","duration":"194.563358ms","start":"2026-02-04T05:15:01.71398Z","end":"2026-02-04T05:15:01.908544Z","steps":["trace[2068855312] 'agreement among raft nodes before linearized reading' (duration: 194.430007ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:02.393068Z","caller":"traceutil/trace.go:171","msg":"trace[964481543] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:519; }","duration":"448.270641ms","start":"2026-02-04T05:15:01.944781Z","end":"2026-02-04T05:15:02.393051Z","steps":["trace[964481543] 'read index received' (duration: 448.265551ms)","trace[964481543] 'applied index is now lower than readState.Index' (duration: 4.06µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:15:02.393081Z","caller":"traceutil/trace.go:171","msg":"trace[677896062] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"466.842668ms","start":"2026-02-04T05:15:01.926205Z","end":"2026-02-04T05:15:02.393047Z","steps":["trace[677896062] 'process raft request' (duration: 466.719397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:02.393352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:01.92599Z","time spent":"467.29821ms","remote":"127.0.0.1:56454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T05:15:02.393261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.467422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-04T05:15:02.39359Z","caller":"traceutil/trace.go:171","msg":"trace[1401421432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:502; }","duration":"448.822355ms","start":"2026-02-04T05:15:01.944757Z","end":"2026-02-04T05:15:02.39358Z","steps":["trace[1401421432] 'agreement among raft nodes before linearized reading' (duration: 448.368341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:02.393663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:01.944746Z","time spent":"448.904436ms","remote":"127.0.0.1:56670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-04T05:15:02.401231Z","caller":"traceutil/trace.go:171","msg":"trace[1512691132] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"456.313474ms","start":"2026-02-04T05:15:01.944909Z","end":"2026-02-04T05:15:02.401222Z","steps":["trace[1512691132] 'process raft request' (duration: 456.203853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:02.401401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:01.944905Z","time spent":"456.470615ms","remote":"127.0.0.1:56670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:15:02.401695Z","caller":"traceutil/trace.go:171","msg":"trace[749620122] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"456.833618ms","start":"2026-02-04T05:15:01.944855Z","end":"2026-02-04T05:15:02.401688Z","steps":["trace[749620122] 'process raft request' (duration: 456.213593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:02.402038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:01.944847Z","time spent":"457.1688ms","remote":"127.0.0.1:56670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:15:02.402939Z","caller":"traceutil/trace.go:171","msg":"trace[1744283415] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"458.001827ms","start":"2026-02-04T05:15:01.944931Z","end":"2026-02-04T05:15:02.402933Z","steps":["trace[1744283415] 'process raft request' (duration: 456.213993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:02.403336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:01.944928Z","time spent":"458.38829ms","remote":"127.0.0.1:56670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:15:02.403765Z","caller":"traceutil/trace.go:171","msg":"trace[1796910211] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"294.150175ms","start":"2026-02-04T05:15:02.109608Z","end":"2026-02-04T05:15:02.403758Z","steps":["trace[1796910211] 'process raft request' (duration: 291.555755ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:02.403977Z","caller":"traceutil/trace.go:171","msg":"trace[514617477] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"249.798909ms","start":"2026-02-04T05:15:02.154172Z","end":"2026-02-04T05:15:02.403971Z","steps":["trace[514617477] 'process raft request' (duration: 247.007787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:02.404284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.30061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:15:02.404734Z","caller":"traceutil/trace.go:171","msg":"trace[981136065] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:0; response_revision:510; }","duration":"101.774884ms","start":"2026-02-04T05:15:02.302951Z","end":"2026-02-04T05:15:02.404726Z","steps":["trace[981136065] 'agreement among raft nodes before linearized reading' (duration: 101.31526ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:09.832602Z","caller":"traceutil/trace.go:171","msg":"trace[1714143216] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"365.313514ms","start":"2026-02-04T05:15:09.467274Z","end":"2026-02-04T05:15:09.832587Z","steps":["trace[1714143216] 'process raft request' (duration: 365.226183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:09.832698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:09.467256Z","time spent":"365.393334ms","remote":"127.0.0.1:56580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:15:09.832944Z","caller":"traceutil/trace.go:171","msg":"trace[1562116010] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:590; }","duration":"175.352432ms","start":"2026-02-04T05:15:09.657586Z","end":"2026-02-04T05:15:09.832939Z","steps":["trace[1562116010] 'read index received' (duration: 175.349992ms)","trace[1562116010] 'applied index is now lower than readState.Index' (duration: 2.13µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:15:09.832989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.361922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:15:09.833004Z","caller":"traceutil/trace.go:171","msg":"trace[1419234890] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:570; }","duration":"175.390092ms","start":"2026-02-04T05:15:09.657609Z","end":"2026-02-04T05:15:09.832999Z","steps":["trace[1419234890] 'agreement among raft nodes before linearized reading' (duration: 175.360242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:09.838776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.176596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:15:09.838918Z","caller":"traceutil/trace.go:171","msg":"trace[191527923] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:570; }","duration":"181.335007ms","start":"2026-02-04T05:15:09.657564Z","end":"2026-02-04T05:15:09.838899Z","steps":["trace[191527923] 'agreement among raft nodes before linearized reading' (duration: 181.173355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:09.839222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.793679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:15:09.839412Z","caller":"traceutil/trace.go:171","msg":"trace[99221888] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:570; }","duration":"107.00538ms","start":"2026-02-04T05:15:09.732395Z","end":"2026-02-04T05:15:09.839401Z","steps":["trace[99221888] 'agreement among raft nodes before linearized reading' (duration: 106.794859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:09.83966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.725423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:15:09.839846Z","caller":"traceutil/trace.go:171","msg":"trace[67263579] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:570; }","duration":"140.913264ms","start":"2026-02-04T05:15:09.698921Z","end":"2026-02-04T05:15:09.839834Z","steps":["trace[67263579] 'agreement among raft nodes before linearized reading' (duration: 140.704743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:09.840198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.281087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:15:09.840285Z","caller":"traceutil/trace.go:171","msg":"trace[388018711] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:570; }","duration":"141.378627ms","start":"2026-02-04T05:15:09.698893Z","end":"2026-02-04T05:15:09.840272Z","steps":["trace[388018711] 'agreement among raft nodes before linearized reading' (duration: 141.268427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:09.840568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.68273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:15:09.840665Z","caller":"traceutil/trace.go:171","msg":"trace[2128097479] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:570; }","duration":"141.793981ms","start":"2026-02-04T05:15:09.698858Z","end":"2026-02-04T05:15:09.840652Z","steps":["trace[2128097479] 'agreement among raft nodes before linearized reading' (duration: 141.67576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:09.840822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.564366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:15:09.840897Z","caller":"traceutil/trace.go:171","msg":"trace[590833310] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:570; }","duration":"150.647037ms","start":"2026-02-04T05:15:09.69024Z","end":"2026-02-04T05:15:09.840887Z","steps":["trace[590833310] 'agreement among raft nodes before linearized reading' (duration: 150.556506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:09.841231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.98153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:15:09.841315Z","caller":"traceutil/trace.go:171","msg":"trace[1863337262] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:570; }","duration":"151.076061ms","start":"2026-02-04T05:15:09.690228Z","end":"2026-02-04T05:15:09.841304Z","steps":["trace[1863337262] 'agreement among raft nodes before linearized reading' (duration: 150.96898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:09.841607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.380463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:15:09.841691Z","caller":"traceutil/trace.go:171","msg":"trace[1212736712] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:570; }","duration":"151.498373ms","start":"2026-02-04T05:15:09.690182Z","end":"2026-02-04T05:15:09.841681Z","steps":["trace[1212736712] 'agreement among raft nodes before linearized reading' (duration: 151.392753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:09.84199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.746495ms","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-04T05:15:09.842071Z","caller":"traceutil/trace.go:171","msg":"trace[22894866] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:570; }","duration":"163.835575ms","start":"2026-02-04T05:15:09.678225Z","end":"2026-02-04T05:15:09.84206Z","steps":["trace[22894866] 'agreement among raft nodes before linearized reading' (duration: 163.733024ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:09.842451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.218468ms","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-04T05:15:09.842541Z","caller":"traceutil/trace.go:171","msg":"trace[1523287842] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:570; }","duration":"164.31802ms","start":"2026-02-04T05:15:09.678212Z","end":"2026-02-04T05:15:09.84253Z","steps":["trace[1523287842] 'agreement among raft nodes before linearized reading' (duration: 164.205379ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:09.842873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.660192ms","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-04T05:15:09.842968Z","caller":"traceutil/trace.go:171","msg":"trace[958483673] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:570; }","duration":"164.779273ms","start":"2026-02-04T05:15:09.678177Z","end":"2026-02-04T05:15:09.842957Z","steps":["trace[958483673] 'agreement among raft nodes before linearized reading' (duration: 164.656852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:09.843289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.64233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:15:09.843376Z","caller":"traceutil/trace.go:171","msg":"trace[708108532] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:570; }","duration":"185.73938ms","start":"2026-02-04T05:15:09.657626Z","end":"2026-02-04T05:15:09.843365Z","steps":["trace[708108532] 'agreement among raft nodes before linearized reading' (duration: 185.596399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:10.233217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.445933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:15:10.233285Z","caller":"traceutil/trace.go:171","msg":"trace[232233271] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:570; }","duration":"176.555584ms","start":"2026-02-04T05:15:10.056713Z","end":"2026-02-04T05:15:10.233268Z","steps":["trace[232233271] 'range keys from in-memory index tree' (duration: 176.369663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:11.377743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.229218ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:15:11.377856Z","caller":"traceutil/trace.go:171","msg":"trace[2044618042] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:591; }","duration":"637.334338ms","start":"2026-02-04T05:15:10.740513Z","end":"2026-02-04T05:15:11.377847Z","steps":["trace[2044618042] 'read index received' (duration: 446.903928ms)","trace[2044618042] 'applied index is now lower than readState.Index' (duration: 190.42992ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:15:11.377992Z","caller":"traceutil/trace.go:171","msg":"trace[55783220] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"859.519011ms","start":"2026-02-04T05:15:10.518467Z","end":"2026-02-04T05:15:11.377986Z","steps":["trace[55783220] 'process raft request' (duration: 668.997941ms)","trace[55783220] 'compare' (duration: 190.101647ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:15:11.378023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:10.518444Z","time spent":"859.563001ms","remote":"127.0.0.1:56670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:15:11.378103Z","caller":"traceutil/trace.go:171","msg":"trace[25000611] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"857.393466ms","start":"2026-02-04T05:15:10.520705Z","end":"2026-02-04T05:15:11.378099Z","steps":["trace[25000611] 'process raft request' (duration: 857.094824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:11.378124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:10.520695Z","time spent":"857.417656ms","remote":"127.0.0.1:56670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:15:11.378174Z","caller":"traceutil/trace.go:171","msg":"trace[817419376] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"856.209836ms","start":"2026-02-04T05:15:10.52196Z","end":"2026-02-04T05:15:11.37817Z","steps":["trace[817419376] 'process raft request' (duration: 855.872654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:11.378194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:10.521952Z","time spent":"856.231026ms","remote":"127.0.0.1:56670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:15:11.378283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.770062ms","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-04T05:15:11.3783Z","caller":"traceutil/trace.go:171","msg":"trace[2100535924] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:574; }","duration":"637.805752ms","start":"2026-02-04T05:15:10.74049Z","end":"2026-02-04T05:15:11.378296Z","steps":["trace[2100535924] 'agreement among raft nodes before linearized reading' (duration: 637.743572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:11.378315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:10.740476Z","time spent":"637.835832ms","remote":"127.0.0.1:56670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-04T05:15:11.378373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.38831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:15:11.378388Z","caller":"traceutil/trace.go:171","msg":"trace[303707495] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:574; }","duration":"633.415951ms","start":"2026-02-04T05:15:10.744969Z","end":"2026-02-04T05:15:11.378385Z","steps":["trace[303707495] 'agreement among raft nodes before linearized reading' (duration: 633.393871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:11.3784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:10.744962Z","time spent":"633.435481ms","remote":"127.0.0.1:56408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T05:15:12.233338Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251526414460655,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T05:15:12.422107Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.039433318s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-04T05:15:13.044444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.13135ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:15:13.044536Z","caller":"traceutil/trace.go:171","msg":"trace[1845496198] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"1.311588927s","start":"2026-02-04T05:15:11.732931Z","end":"2026-02-04T05:15:13.04452Z","steps":["trace[1845496198] 'read index received' (duration: 689.280586ms)","trace[1845496198] 'applied index is now lower than readState.Index' (duration: 622.307081ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:15:13.044711Z","caller":"traceutil/trace.go:171","msg":"trace[419024582] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"1.662082072s","start":"2026-02-04T05:15:11.382616Z","end":"2026-02-04T05:15:13.044698Z","steps":["trace[419024582] 'process raft request' (duration: 1.039600789s)","trace[419024582] 'compare' (duration: 621.819868ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:15:13.044774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:11.382604Z","time spent":"1.662133743s","remote":"127.0.0.1:56670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:15:13.044936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.31200145s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:15:13.044963Z","caller":"traceutil/trace.go:171","msg":"trace[126239007] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"1.312051771s","start":"2026-02-04T05:15:11.732902Z","end":"2026-02-04T05:15:13.044954Z","steps":["trace[126239007] 'agreement among raft nodes before linearized reading' (duration: 1.312003921s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:13.044989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:11.732889Z","time spent":"1.312094132s","remote":"127.0.0.1:56406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-04T05:15:13.096914Z","caller":"traceutil/trace.go:171","msg":"trace[1033538784] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"487.291906ms","start":"2026-02-04T05:15:12.609606Z","end":"2026-02-04T05:15:13.096898Z","steps":["trace[1033538784] 'process raft request' (duration: 487.077444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:13.097008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:12.60959Z","time spent":"487.368766ms","remote":"127.0.0.1:56670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:15:13.097172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.077824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.41\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-04T05:15:13.097192Z","caller":"traceutil/trace.go:171","msg":"trace[1071182474] range","detail":"{range_begin:/registry/masterleases/199.204.45.41; range_end:; response_count:1; response_revision:576; }","duration":"237.130954ms","start":"2026-02-04T05:15:12.860055Z","end":"2026-02-04T05:15:13.097186Z","steps":["trace[1071182474] 'agreement among raft nodes before linearized reading' (duration: 237.059353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:13.097304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.702858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-04T05:15:13.097332Z","caller":"traceutil/trace.go:171","msg":"trace[857396172] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:576; }","duration":"590.755728ms","start":"2026-02-04T05:15:12.50657Z","end":"2026-02-04T05:15:13.097326Z","steps":["trace[857396172] 'agreement among raft nodes before linearized reading' (duration: 590.698007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:13.097348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:12.506556Z","time spent":"590.788288ms","remote":"127.0.0.1:56670","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-04T05:16:28.316405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.568961ms","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-04T05:16:28.316493Z","caller":"traceutil/trace.go:171","msg":"trace[1398825768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:926; }","duration":"225.728114ms","start":"2026-02-04T05:16:28.090747Z","end":"2026-02-04T05:16:28.316475Z","steps":["trace[1398825768] 'range keys from in-memory index tree' (duration: 225.340666ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:16:37.140172Z","caller":"traceutil/trace.go:171","msg":"trace[1023121042] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"256.468813ms","start":"2026-02-04T05:16:36.883687Z","end":"2026-02-04T05:16:37.140156Z","steps":["trace[1023121042] 'process raft request' (duration: 256.363471ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:16:37.310134Z","caller":"traceutil/trace.go:171","msg":"trace[917769991] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"119.356932ms","start":"2026-02-04T05:16:37.190764Z","end":"2026-02-04T05:16:37.310121Z","steps":["trace[917769991] 'process raft request' (duration: 33.264256ms)","trace[917769991] 'compare' (duration: 85.830751ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:16:38.051991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.441197ms","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-04T05:16:38.052053Z","caller":"traceutil/trace.go:171","msg":"trace[1556946341] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:957; }","duration":"467.54616ms","start":"2026-02-04T05:16:37.584492Z","end":"2026-02-04T05:16:38.052038Z","steps":["trace[1556946341] 'range keys from in-memory index tree' (duration: 467.235603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:16:38.052088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:16:37.584474Z","time spent":"467.606771ms","remote":"127.0.0.1:56670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-04T05:16:38.052905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.128589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-04T05:16:38.053093Z","caller":"traceutil/trace.go:171","msg":"trace[2145817551] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:957; }","duration":"448.360614ms","start":"2026-02-04T05:16:37.60471Z","end":"2026-02-04T05:16:38.053071Z","steps":["trace[2145817551] 'range keys from in-memory index tree' (duration: 447.978766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:16:38.053306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:16:37.604688Z","time spent":"448.603169ms","remote":"127.0.0.1:56670","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-04T05:16:38.05362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.728344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T05:16:38.053755Z","caller":"traceutil/trace.go:171","msg":"trace[682671301] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:957; }","duration":"373.906818ms","start":"2026-02-04T05:16:37.679833Z","end":"2026-02-04T05:16:38.053739Z","steps":["trace[682671301] 'count revisions from in-memory index tree' (duration: 373.671423ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:16:38.05394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:16:37.679817Z","time spent":"374.106262ms","remote":"127.0.0.1:36468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-02-04T05:16:38.055002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.024728ms","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-04T05:16:38.058244Z","caller":"traceutil/trace.go:171","msg":"trace[1119768245] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:957; }","duration":"140.303016ms","start":"2026-02-04T05:16:37.917928Z","end":"2026-02-04T05:16:38.058232Z","steps":["trace[1119768245] 'range keys from in-memory index tree' (duration: 136.860275ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:16:38.057458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.176969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:16:38.058638Z","caller":"traceutil/trace.go:171","msg":"trace[778426344] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:957; }","duration":"323.383333ms","start":"2026-02-04T05:16:37.735229Z","end":"2026-02-04T05:16:38.058612Z","steps":["trace[778426344] 'range keys from in-memory index tree' (duration: 322.112868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:16:38.060738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:16:37.73517Z","time spent":"325.556868ms","remote":"127.0.0.1:56406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T05:16:38.177538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.008443ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-04T05:16:38.177605Z","caller":"traceutil/trace.go:171","msg":"trace[498914541] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:995; }","duration":"132.160616ms","start":"2026-02-04T05:16:38.045432Z","end":"2026-02-04T05:16:38.177593Z","steps":["trace[498914541] 'read index received' (duration: 34.881µs)","trace[498914541] 'applied index is now lower than readState.Index' (duration: 132.124725ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:16:38.177741Z","caller":"traceutil/trace.go:171","msg":"trace[326089250] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"116.497945ms","start":"2026-02-04T05:16:38.061237Z","end":"2026-02-04T05:16:38.177735Z","steps":["trace[326089250] 'process raft request' (duration: 116.418914ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:16:38.207437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.985597ms","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-04T05:16:38.209678Z","caller":"traceutil/trace.go:171","msg":"trace[1308483560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:958; }","duration":"164.270073ms","start":"2026-02-04T05:16:38.045395Z","end":"2026-02-04T05:16:38.209665Z","steps":["trace[1308483560] 'agreement among raft nodes before linearized reading' (duration: 132.592564ms)","trace[1308483560] 'range keys from in-memory index tree' (duration: 29.346881ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:16:38.208494Z","caller":"traceutil/trace.go:171","msg":"trace[517723985] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"140.328233ms","start":"2026-02-04T05:16:38.068149Z","end":"2026-02-04T05:16:38.208477Z","steps":["trace[517723985] 'process raft request' (duration: 136.617177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:16:38.209098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.129072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:16:38.209609Z","caller":"traceutil/trace.go:171","msg":"trace[557857441] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"139.802732ms","start":"2026-02-04T05:16:38.069791Z","end":"2026-02-04T05:16:38.209593Z","steps":["trace[557857441] 'process raft request' (duration: 138.628699ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:16:38.213306Z","caller":"traceutil/trace.go:171","msg":"trace[277361061] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:960; }","duration":"150.340129ms","start":"2026-02-04T05:16:38.062956Z","end":"2026-02-04T05:16:38.213296Z","steps":["trace[277361061] 'agreement among raft nodes before linearized reading' (duration: 146.079881ms)"],"step_count":1}