{"level":"warn","ts":"2026-02-01T18:47:30.281236Z","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-01T18:47:30.281347Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.195: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://162.253.55.195:2380","--initial-cluster=instance=https://162.253.55.195:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.195:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.195: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-01T18:47:30.281409Z","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-01T18:47:30.281422Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.195:2380"]} {"level":"info","ts":"2026-02-01T18:47:30.281454Z","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-01T18:47:30.282022Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"]} {"level":"info","ts":"2026-02-01T18:47:30.282131Z","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://162.253.55.195:2380"],"listen-peer-urls":["https://162.253.55.195:2380"],"advertise-client-urls":["https://162.253.55.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.195: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-01T18:47:30.291503Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.169107ms"} {"level":"info","ts":"2026-02-01T18:47:30.299671Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b66d12fd59101739","cluster-id":"a64a525934ce8379"} {"level":"info","ts":"2026-02-01T18:47:30.29972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=()"} {"level":"info","ts":"2026-02-01T18:47:30.299743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became follower at term 0"} {"level":"info","ts":"2026-02-01T18:47:30.299754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b66d12fd59101739 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-01T18:47:30.29976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became follower at term 1"} {"level":"info","ts":"2026-02-01T18:47:30.299787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=(13145183766694598457)"} {"level":"warn","ts":"2026-02-01T18:47:30.318785Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-01T18:47:30.339204Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-01T18:47:30.360037Z","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-01T18:47:30.368446Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b66d12fd59101739","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-01T18:47:30.370259Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b66d12fd59101739","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-01T18:47:30.370716Z","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-01T18:47:30.370755Z","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-01T18:47:30.370762Z","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-01T18:47:30.371261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=(13145183766694598457)"} {"level":"info","ts":"2026-02-01T18:47:30.371445Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a64a525934ce8379","local-member-id":"b66d12fd59101739","added-peer-id":"b66d12fd59101739","added-peer-peer-urls":["https://162.253.55.195:2380"]} {"level":"info","ts":"2026-02-01T18:47:30.371602Z","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-01T18:47:30.371797Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.195:2380"} {"level":"info","ts":"2026-02-01T18:47:30.371808Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.195:2380"} {"level":"info","ts":"2026-02-01T18:47:30.372157Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b66d12fd59101739","initial-advertise-peer-urls":["https://162.253.55.195:2380"],"listen-peer-urls":["https://162.253.55.195:2380"],"advertise-client-urls":["https://162.253.55.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-01T18:47:30.372266Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-01T18:47:30.804042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 is starting a new election at term 1"} {"level":"info","ts":"2026-02-01T18:47:30.80409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-01T18:47:30.804108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 received MsgPreVoteResp from b66d12fd59101739 at term 1"} {"level":"info","ts":"2026-02-01T18:47:30.804119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became candidate at term 2"} {"level":"info","ts":"2026-02-01T18:47:30.804126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 received MsgVoteResp from b66d12fd59101739 at term 2"} {"level":"info","ts":"2026-02-01T18:47:30.804135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became leader at term 2"} {"level":"info","ts":"2026-02-01T18:47:30.804142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b66d12fd59101739 elected leader b66d12fd59101739 at term 2"} {"level":"info","ts":"2026-02-01T18:47:30.815443Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b66d12fd59101739","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.195:2379]}","request-path":"/0/members/b66d12fd59101739/attributes","cluster-id":"a64a525934ce8379","publish-timeout":"7s"} {"level":"info","ts":"2026-02-01T18:47:30.815473Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-01T18:47:30.815742Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:47:30.81958Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.195:2379"} {"level":"info","ts":"2026-02-01T18:47:30.816896Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-01T18:47:30.827924Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-01T18:47:30.828417Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a64a525934ce8379","local-member-id":"b66d12fd59101739","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:47:30.828532Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:47:30.82855Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:47:30.871986Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-01T18:47:30.872027Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-02-01T18:48:05.142191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.170658ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:48:05.142292Z","caller":"traceutil/trace.go:171","msg":"trace[1399913993] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"256.446416ms","start":"2026-02-01T18:48:04.885824Z","end":"2026-02-01T18:48:05.142271Z","steps":["trace[1399913993] 'process raft request' (duration: 21.649816ms)","trace[1399913993] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-controller-manager; req_size:488; } (duration: 233.897073ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:48:05.146643Z","caller":"traceutil/trace.go:171","msg":"trace[1951266234] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:569; }","duration":"205.100388ms","start":"2026-02-01T18:48:04.941519Z","end":"2026-02-01T18:48:05.146619Z","steps":["trace[1951266234] 'read index received' (duration: 204.895284ms)","trace[1951266234] 'applied index is now lower than readState.Index' (duration: 204.264µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:48:05.14685Z","caller":"traceutil/trace.go:171","msg":"trace[604874279] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"214.881755ms","start":"2026-02-01T18:48:04.931957Z","end":"2026-02-01T18:48:05.146838Z","steps":["trace[604874279] 'process raft request' (duration: 214.557029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:05.147103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.533587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:48:05.147137Z","caller":"traceutil/trace.go:171","msg":"trace[891937044] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:554; }","duration":"205.644069ms","start":"2026-02-01T18:48:04.941483Z","end":"2026-02-01T18:48:05.147127Z","steps":["trace[891937044] 'agreement among raft nodes before linearized reading' (duration: 205.530717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:11.873678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.869498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-px8l7\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-01T18:48:11.873742Z","caller":"traceutil/trace.go:171","msg":"trace[1853726494] range","detail":"{range_begin:/registry/pods/kube-system/cilium-px8l7; range_end:; response_count:1; response_revision:585; }","duration":"200.966899ms","start":"2026-02-01T18:48:11.672761Z","end":"2026-02-01T18:48:11.873728Z","steps":["trace[1853726494] 'range keys from in-memory index tree' (duration: 200.739675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:11.873937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.902175ms","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-01T18:48:11.873955Z","caller":"traceutil/trace.go:171","msg":"trace[1077928109] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:585; }","duration":"391.945696ms","start":"2026-02-01T18:48:11.482004Z","end":"2026-02-01T18:48:11.87395Z","steps":["trace[1077928109] 'range keys from in-memory index tree' (duration: 391.823023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:11.873991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:11.481989Z","time spent":"391.978017ms","remote":"127.0.0.1:58152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-01T18:48:11.874097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.062315ms","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-01T18:48:11.874116Z","caller":"traceutil/trace.go:171","msg":"trace[1139948239] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:585; }","duration":"370.090785ms","start":"2026-02-01T18:48:11.504019Z","end":"2026-02-01T18:48:11.87411Z","steps":["trace[1139948239] 'count revisions from in-memory index tree' (duration: 370.040634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:11.874135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:11.504016Z","time spent":"370.114086ms","remote":"127.0.0.1:60694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-02-01T18:48:11.874228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.249118ms","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-01T18:48:11.874244Z","caller":"traceutil/trace.go:171","msg":"trace[1398527547] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:585; }","duration":"370.278929ms","start":"2026-02-01T18:48:11.503961Z","end":"2026-02-01T18:48:11.874239Z","steps":["trace[1398527547] 'range keys from in-memory index tree' (duration: 370.133976ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:11.874263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:11.50395Z","time spent":"370.308389ms","remote":"127.0.0.1:60694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-02-01T18:48:11.874328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.247808ms","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-01T18:48:11.874343Z","caller":"traceutil/trace.go:171","msg":"trace[1672419297] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:585; }","duration":"370.281429ms","start":"2026-02-01T18:48:11.504057Z","end":"2026-02-01T18:48:11.874339Z","steps":["trace[1672419297] 'range keys from in-memory index tree' (duration: 370.228057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:11.87436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:11.504055Z","time spent":"370.300079ms","remote":"127.0.0.1:60694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-02-01T18:48:11.874428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.917434ms","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-01T18:48:11.87446Z","caller":"traceutil/trace.go:171","msg":"trace[1784062815] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:585; }","duration":"352.972066ms","start":"2026-02-01T18:48:11.521483Z","end":"2026-02-01T18:48:11.874455Z","steps":["trace[1784062815] 'count revisions from in-memory index tree' (duration: 352.842613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:11.874478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:11.521476Z","time spent":"352.996476ms","remote":"127.0.0.1:60698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-01T18:48:11.874554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.152969ms","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-01T18:48:11.874572Z","caller":"traceutil/trace.go:171","msg":"trace[671387199] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:585; }","duration":"353.21318ms","start":"2026-02-01T18:48:11.521353Z","end":"2026-02-01T18:48:11.874567Z","steps":["trace[671387199] 'range keys from in-memory index tree' (duration: 352.780002ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:11.874592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:11.52134Z","time spent":"353.24723ms","remote":"127.0.0.1:60698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-02-01T18:48:11.874654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.931504ms","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-01T18:48:11.874671Z","caller":"traceutil/trace.go:171","msg":"trace[1931736398] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:585; }","duration":"352.964746ms","start":"2026-02-01T18:48:11.5217Z","end":"2026-02-01T18:48:11.874665Z","steps":["trace[1931736398] 'range keys from in-memory index tree' (duration: 352.864553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:11.874687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:11.521691Z","time spent":"352.991426ms","remote":"127.0.0.1:60698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-02-01T18:48:11.874775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.770172ms","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-01T18:48:11.874791Z","caller":"traceutil/trace.go:171","msg":"trace[673121534] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:585; }","duration":"352.822653ms","start":"2026-02-01T18:48:11.521963Z","end":"2026-02-01T18:48:11.874786Z","steps":["trace[673121534] 'range keys from in-memory index tree' (duration: 352.70999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:11.874807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:11.521957Z","time spent":"352.844963ms","remote":"127.0.0.1:58152","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-01T18:48:11.874877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.814203ms","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-01T18:48:11.874893Z","caller":"traceutil/trace.go:171","msg":"trace[741348506] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:585; }","duration":"338.950626ms","start":"2026-02-01T18:48:11.535937Z","end":"2026-02-01T18:48:11.874888Z","steps":["trace[741348506] 'count revisions from in-memory index tree' (duration: 338.789703ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:11.874911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:11.535927Z","time spent":"338.979286ms","remote":"127.0.0.1:60702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-02-01T18:48:11.874997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.997887ms","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-01T18:48:11.875013Z","caller":"traceutil/trace.go:171","msg":"trace[1432423941] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:585; }","duration":"339.029417ms","start":"2026-02-01T18:48:11.535979Z","end":"2026-02-01T18:48:11.875009Z","steps":["trace[1432423941] 'range keys from in-memory index tree' (duration: 338.854734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:11.87503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:11.535974Z","time spent":"339.051008ms","remote":"127.0.0.1:60702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-02-01T18:48:11.875114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.923045ms","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-01T18:48:11.875131Z","caller":"traceutil/trace.go:171","msg":"trace[1416177769] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:585; }","duration":"338.961815ms","start":"2026-02-01T18:48:11.536165Z","end":"2026-02-01T18:48:11.875127Z","steps":["trace[1416177769] 'range keys from in-memory index tree' (duration: 338.858134ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:11.875162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:11.536159Z","time spent":"338.997377ms","remote":"127.0.0.1:60702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-02-01T18:48:11.87523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.742742ms","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-01T18:48:11.875246Z","caller":"traceutil/trace.go:171","msg":"trace[1340007325] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:585; }","duration":"323.768483ms","start":"2026-02-01T18:48:11.551472Z","end":"2026-02-01T18:48:11.875241Z","steps":["trace[1340007325] 'range keys from in-memory index tree' (duration: 323.718902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:11.875263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:11.551469Z","time spent":"323.789893ms","remote":"127.0.0.1:60706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 "} {"level":"warn","ts":"2026-02-01T18:48:11.87534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.897695ms","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-01T18:48:11.875357Z","caller":"traceutil/trace.go:171","msg":"trace[594352735] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:585; }","duration":"323.925166ms","start":"2026-02-01T18:48:11.551426Z","end":"2026-02-01T18:48:11.875351Z","steps":["trace[594352735] 'range keys from in-memory index tree' (duration: 323.791383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:11.875464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:11.551419Z","time spent":"324.036828ms","remote":"127.0.0.1:60706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 "} {"level":"warn","ts":"2026-02-01T18:48:11.875551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.017167ms","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-01T18:48:11.875568Z","caller":"traceutil/trace.go:171","msg":"trace[315560577] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:585; }","duration":"324.034348ms","start":"2026-02-01T18:48:11.551528Z","end":"2026-02-01T18:48:11.875563Z","steps":["trace[315560577] 'count revisions from in-memory index tree' (duration: 323.994577ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:11.875586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:11.551516Z","time spent":"324.064558ms","remote":"127.0.0.1:60706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-02-01T18:48:23.740089Z","caller":"traceutil/trace.go:171","msg":"trace[1413918740] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"114.441224ms","start":"2026-02-01T18:48:23.62563Z","end":"2026-02-01T18:48:23.740071Z","steps":["trace[1413918740] 'process raft request' (duration: 114.335942ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:32.23235Z","caller":"traceutil/trace.go:171","msg":"trace[1560890088] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"240.198683ms","start":"2026-02-01T18:48:31.992136Z","end":"2026-02-01T18:48:32.232334Z","steps":["trace[1560890088] 'process raft request' (duration: 240.095732ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:32.28085Z","caller":"traceutil/trace.go:171","msg":"trace[486994572] linearizableReadLoop","detail":"{readStateIndex:753; appliedIndex:752; }","duration":"108.09634ms","start":"2026-02-01T18:48:32.172735Z","end":"2026-02-01T18:48:32.280831Z","steps":["trace[486994572] 'read index received' (duration: 59.933903ms)","trace[486994572] 'applied index is now lower than readState.Index' (duration: 48.161657ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:48:32.280862Z","caller":"traceutil/trace.go:171","msg":"trace[1954238160] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"177.451585ms","start":"2026-02-01T18:48:32.103395Z","end":"2026-02-01T18:48:32.280846Z","steps":["trace[1954238160] 'process raft request' (duration: 177.333093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:32.281039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.271024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-01T18:48:32.281065Z","caller":"traceutil/trace.go:171","msg":"trace[1023075491] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:730; }","duration":"108.344956ms","start":"2026-02-01T18:48:32.172712Z","end":"2026-02-01T18:48:32.281057Z","steps":["trace[1023075491] 'agreement among raft nodes before linearized reading' (duration: 108.188212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:45.687514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.298737ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:48:45.687581Z","caller":"traceutil/trace.go:171","msg":"trace[1636488252] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"176.62707ms","start":"2026-02-01T18:48:45.51094Z","end":"2026-02-01T18:48:45.687567Z","steps":["trace[1636488252] 'process raft request' (duration: 17.216401ms)","trace[1636488252] 'compare' (duration: 159.231266ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:48:50.088572Z","caller":"traceutil/trace.go:171","msg":"trace[122403936] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"142.222558ms","start":"2026-02-01T18:48:49.946319Z","end":"2026-02-01T18:48:50.088542Z","steps":["trace[122403936] 'read index received' (duration: 98.977196ms)","trace[122403936] 'applied index is now lower than readState.Index' (duration: 43.243922ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:48:50.088735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.374091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:48:50.088769Z","caller":"traceutil/trace.go:171","msg":"trace[459976452] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:786; }","duration":"142.470122ms","start":"2026-02-01T18:48:49.946289Z","end":"2026-02-01T18:48:50.088759Z","steps":["trace[459976452] 'agreement among raft nodes before linearized reading' (duration: 142.36152ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:49:01.721772Z","caller":"traceutil/trace.go:171","msg":"trace[1991686352] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"141.126274ms","start":"2026-02-01T18:49:01.580615Z","end":"2026-02-01T18:49:01.721742Z","steps":["trace[1991686352] 'process raft request' (duration: 140.951101ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:49:04.889425Z","caller":"traceutil/trace.go:171","msg":"trace[1378866379] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"126.17875ms","start":"2026-02-01T18:49:04.76323Z","end":"2026-02-01T18:49:04.889409Z","steps":["trace[1378866379] 'process raft request' (duration: 126.082668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:34.066407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.892665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-01T18:49:34.066418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.803845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:49:34.081078Z","caller":"traceutil/trace.go:171","msg":"trace[208684071] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1023; }","duration":"112.917708ms","start":"2026-02-01T18:49:33.953566Z","end":"2026-02-01T18:49:34.066484Z","steps":["trace[208684071] 'range keys from in-memory index tree' (duration: 112.754485ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:49:34.081223Z","caller":"traceutil/trace.go:171","msg":"trace[429500511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1023; }","duration":"197.983866ms","start":"2026-02-01T18:49:33.868465Z","end":"2026-02-01T18:49:34.066449Z","steps":["trace[429500511] 'range keys from in-memory index tree' (duration: 197.63587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:34.081094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.964452ms","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-01T18:49:34.082352Z","caller":"traceutil/trace.go:171","msg":"trace[1942458635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1023; }","duration":"172.253337ms","start":"2026-02-01T18:49:33.910083Z","end":"2026-02-01T18:49:34.082336Z","steps":["trace[1942458635] 'range keys from in-memory index tree' (duration: 170.85981ms)"],"step_count":1}