{"level":"warn","ts":"2026-02-01T18:49:22.394171Z","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:49:22.394589Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.30: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.30:2380","--initial-cluster=instance=https://199.204.45.30:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.30:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.30: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:49:22.394829Z","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:49:22.395087Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-02-01T18:49:22.395776Z","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:49:22.396992Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"]} {"level":"info","ts":"2026-02-01T18:49:22.40547Z","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.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.30: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:49:22.413432Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.54291ms"} {"level":"info","ts":"2026-02-01T18:49:22.425417Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c6cf68dde442ffa5","cluster-id":"98b7ecdca9045526"} {"level":"info","ts":"2026-02-01T18:49:22.425527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=()"} {"level":"info","ts":"2026-02-01T18:49:22.425561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 0"} {"level":"info","ts":"2026-02-01T18:49:22.425617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c6cf68dde442ffa5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-01T18:49:22.425675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 1"} {"level":"info","ts":"2026-02-01T18:49:22.425846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"warn","ts":"2026-02-01T18:49:22.435855Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-01T18:49:22.4403Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-01T18:49:22.442277Z","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:49:22.45002Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c6cf68dde442ffa5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-01T18:49:22.458171Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c6cf68dde442ffa5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-01T18:49:22.458517Z","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:49:22.461413Z","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:49:22.461509Z","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:49:22.45874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"info","ts":"2026-02-01T18:49:22.462349Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","added-peer-id":"c6cf68dde442ffa5","added-peer-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-02-01T18:49:22.469759Z","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:49:22.470151Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c6cf68dde442ffa5","initial-advertise-peer-urls":["https://199.204.45.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-01T18:49:22.47028Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-01T18:49:22.469893Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-02-01T18:49:22.471643Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-02-01T18:49:22.926789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 is starting a new election at term 1"} {"level":"info","ts":"2026-02-01T18:49:22.92691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-01T18:49:22.926949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgPreVoteResp from c6cf68dde442ffa5 at term 1"} {"level":"info","ts":"2026-02-01T18:49:22.927033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became candidate at term 2"} {"level":"info","ts":"2026-02-01T18:49:22.927063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgVoteResp from c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-02-01T18:49:22.927094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became leader at term 2"} {"level":"info","ts":"2026-02-01T18:49:22.927413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c6cf68dde442ffa5 elected leader c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-02-01T18:49:22.93082Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:49:22.932413Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c6cf68dde442ffa5","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.30:2379]}","request-path":"/0/members/c6cf68dde442ffa5/attributes","cluster-id":"98b7ecdca9045526","publish-timeout":"7s"} {"level":"info","ts":"2026-02-01T18:49:22.9325Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-01T18:49:22.93258Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-01T18:49:22.932623Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-01T18:49:22.932717Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:49:22.932925Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:49:22.932983Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:49:22.932941Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-01T18:49:22.936024Z","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:49:22.938359Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.30:2379"} {"level":"info","ts":"2026-02-01T18:49:52.609181Z","caller":"traceutil/trace.go:171","msg":"trace[901875169] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"116.751994ms","start":"2026-02-01T18:49:52.492411Z","end":"2026-02-01T18:49:52.609163Z","steps":["trace[901875169] 'process raft request' (duration: 114.574095ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:49:53.419161Z","caller":"traceutil/trace.go:171","msg":"trace[960754994] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"126.834363ms","start":"2026-02-01T18:49:53.292302Z","end":"2026-02-01T18:49:53.419136Z","steps":["trace[960754994] 'process raft request' (duration: 126.057387ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:49:53.422912Z","caller":"traceutil/trace.go:171","msg":"trace[2108783985] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"127.948563ms","start":"2026-02-01T18:49:53.290986Z","end":"2026-02-01T18:49:53.418934Z","steps":["trace[2108783985] 'process raft request' (duration: 127.207557ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:49:53.850386Z","caller":"traceutil/trace.go:171","msg":"trace[1978651175] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"191.98096ms","start":"2026-02-01T18:49:53.65839Z","end":"2026-02-01T18:49:53.850371Z","steps":["trace[1978651175] 'process raft request' (duration: 191.755328ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:49:54.768584Z","caller":"traceutil/trace.go:171","msg":"trace[2000370900] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"498.72843ms","start":"2026-02-01T18:49:54.269839Z","end":"2026-02-01T18:49:54.768567Z","steps":["trace[2000370900] 'process raft request' (duration: 498.607729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:54.768879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:49:54.269813Z","time spent":"498.818271ms","remote":"127.0.0.1:56928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-01T18:49:54.777605Z","caller":"traceutil/trace.go:171","msg":"trace[2130419799] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:523; }","duration":"299.362817ms","start":"2026-02-01T18:49:54.47823Z","end":"2026-02-01T18:49:54.777593Z","steps":["trace[2130419799] 'read index received' (duration: 290.289522ms)","trace[2130419799] 'applied index is now lower than readState.Index' (duration: 9.072785ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:49:54.781908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.679083ms","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-01T18:49:54.78238Z","caller":"traceutil/trace.go:171","msg":"trace[1820483022] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:504; }","duration":"304.153727ms","start":"2026-02-01T18:49:54.478209Z","end":"2026-02-01T18:49:54.782363Z","steps":["trace[1820483022] 'agreement among raft nodes before linearized reading' (duration: 300.282134ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:54.782515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:49:54.478199Z","time spent":"304.220699ms","remote":"127.0.0.1:57148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-01T18:49:54.778192Z","caller":"traceutil/trace.go:171","msg":"trace[1875101236] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"328.235308ms","start":"2026-02-01T18:49:54.449937Z","end":"2026-02-01T18:49:54.778172Z","steps":["trace[1875101236] 'process raft request' (duration: 327.566023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:54.791764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:49:54.449895Z","time spent":"341.803672ms","remote":"127.0.0.1:56928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-01T18:49:54.778232Z","caller":"traceutil/trace.go:171","msg":"trace[887635194] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"132.579946ms","start":"2026-02-01T18:49:54.645647Z","end":"2026-02-01T18:49:54.778227Z","steps":["trace[887635194] 'process raft request' (duration: 131.924561ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:54.8033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.960863ms","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:54.803591Z","caller":"traceutil/trace.go:171","msg":"trace[1348770620] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:504; }","duration":"125.070223ms","start":"2026-02-01T18:49:54.678271Z","end":"2026-02-01T18:49:54.803341Z","steps":["trace[1348770620] 'agreement among raft nodes before linearized reading' (duration: 113.020673ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:55.590403Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301488623353442,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:49:56.049509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"984.483612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-02-01T18:49:56.04958Z","caller":"traceutil/trace.go:171","msg":"trace[2041664396] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:506; }","duration":"984.588174ms","start":"2026-02-01T18:49:55.064977Z","end":"2026-02-01T18:49:56.049565Z","steps":["trace[2041664396] 'range keys from in-memory index tree' (duration: 984.364942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:56.049609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:49:55.064962Z","time spent":"984.639154ms","remote":"127.0.0.1:56928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13253,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-01T18:49:56.049873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.321794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-01T18:49:56.049897Z","caller":"traceutil/trace.go:171","msg":"trace[190822267] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:506; }","duration":"976.388925ms","start":"2026-02-01T18:49:55.0735Z","end":"2026-02-01T18:49:56.049889Z","steps":["trace[190822267] 'range keys from in-memory index tree' (duration: 976.251603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:56.049916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:49:55.073485Z","time spent":"976.424935ms","remote":"127.0.0.1:57148","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-01T18:49:56.090789Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301488623353442,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:49:56.10488Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.014957293s","expected-duration":"1s"} {"level":"info","ts":"2026-02-01T18:49:56.105971Z","caller":"traceutil/trace.go:171","msg":"trace[392870812] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"1.016131012s","start":"2026-02-01T18:49:55.089811Z","end":"2026-02-01T18:49:56.105942Z","steps":["trace[392870812] 'process raft request' (duration: 1.015471237s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:56.10744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:49:55.089794Z","time spent":"1.016479765s","remote":"127.0.0.1:56928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24220,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:49:56.358997Z","caller":"traceutil/trace.go:171","msg":"trace[756330621] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"1.194890336s","start":"2026-02-01T18:49:55.164089Z","end":"2026-02-01T18:49:56.358979Z","steps":["trace[756330621] 'process raft request' (duration: 1.194366082s)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:49:56.359031Z","caller":"traceutil/trace.go:171","msg":"trace[894299142] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:528; }","duration":"1.268939521s","start":"2026-02-01T18:49:55.090083Z","end":"2026-02-01T18:49:56.359022Z","steps":["trace[894299142] 'read index received' (duration: 1.015176515s)","trace[894299142] 'applied index is now lower than readState.Index' (duration: 253.762546ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:49:56.35908Z","caller":"traceutil/trace.go:171","msg":"trace[276577898] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"714.77882ms","start":"2026-02-01T18:49:55.644297Z","end":"2026-02-01T18:49:56.359076Z","steps":["trace[276577898] 'process raft request' (duration: 714.630898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:56.359096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:49:55.164072Z","time spent":"1.194972876s","remote":"127.0.0.1:56928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:49:56.35911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:49:55.644279Z","time spent":"714.81233ms","remote":"127.0.0.1:56928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-01T18:49:56.3594Z","caller":"traceutil/trace.go:171","msg":"trace[1715778734] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"304.361745ms","start":"2026-02-01T18:49:56.055031Z","end":"2026-02-01T18:49:56.359393Z","steps":["trace[1715778734] 'process raft request' (duration: 303.970971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:56.35944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:49:56.055007Z","time spent":"304.417185ms","remote":"127.0.0.1:57148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:49:56.359574Z","caller":"traceutil/trace.go:171","msg":"trace[256399932] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"479.127873ms","start":"2026-02-01T18:49:55.880441Z","end":"2026-02-01T18:49:56.359568Z","steps":["trace[256399932] 'process raft request' (duration: 478.506988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:56.359602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:49:55.880424Z","time spent":"479.162484ms","remote":"127.0.0.1:57148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:49:56.35966Z","caller":"traceutil/trace.go:171","msg":"trace[219082940] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"315.787759ms","start":"2026-02-01T18:49:56.043867Z","end":"2026-02-01T18:49:56.359654Z","steps":["trace[219082940] 'process raft request' (duration: 315.104613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:56.359679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:49:56.04385Z","time spent":"315.819989ms","remote":"127.0.0.1:56928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-01T18:49:56.359829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.269745249s","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-01T18:49:56.359847Z","caller":"traceutil/trace.go:171","msg":"trace[1167119434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:512; }","duration":"1.269778889s","start":"2026-02-01T18:49:55.090063Z","end":"2026-02-01T18:49:56.359842Z","steps":["trace[1167119434] 'agreement among raft nodes before linearized reading' (duration: 1.269743998s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:56.359869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:49:55.090057Z","time spent":"1.269800719s","remote":"127.0.0.1:57148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T18:49:56.67937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.551031ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:49:56.67946Z","caller":"traceutil/trace.go:171","msg":"trace[1960482238] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:534; }","duration":"320.382358ms","start":"2026-02-01T18:49:56.35905Z","end":"2026-02-01T18:49:56.679433Z","steps":["trace[1960482238] 'read index received' (duration: 208.634305ms)","trace[1960482238] 'applied index is now lower than readState.Index' (duration: 111.747223ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:49:56.679656Z","caller":"traceutil/trace.go:171","msg":"trace[1205189155] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"567.012866ms","start":"2026-02-01T18:49:56.112632Z","end":"2026-02-01T18:49:56.679645Z","steps":["trace[1205189155] 'process raft request' (duration: 455.013381ms)","trace[1205189155] 'compare' (duration: 111.45537ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:49:56.679714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:49:56.112617Z","time spent":"567.064916ms","remote":"127.0.0.1:56928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10353,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:49:56.679959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.150594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-01T18:49:56.679992Z","caller":"traceutil/trace.go:171","msg":"trace[675945084] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:513; }","duration":"436.212054ms","start":"2026-02-01T18:49:56.243769Z","end":"2026-02-01T18:49:56.679981Z","steps":["trace[675945084] 'agreement among raft nodes before linearized reading' (duration: 436.101503ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:56.680019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:49:56.243738Z","time spent":"436.274124ms","remote":"127.0.0.1:56928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4107,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-02-01T18:49:56.680161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.227862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.30\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-01T18:49:56.68019Z","caller":"traceutil/trace.go:171","msg":"trace[1041169419] range","detail":"{range_begin:/registry/masterleases/199.204.45.30; range_end:; response_count:1; response_revision:513; }","duration":"501.290912ms","start":"2026-02-01T18:49:56.178891Z","end":"2026-02-01T18:49:56.680181Z","steps":["trace[1041169419] 'agreement among raft nodes before linearized reading' (duration: 501.230651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:56.680218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:49:56.178876Z","time spent":"501.335672ms","remote":"127.0.0.1:56898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.30\" "} {"level":"warn","ts":"2026-02-01T18:49:56.680395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.433275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-01T18:49:56.680423Z","caller":"traceutil/trace.go:171","msg":"trace[1666389753] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:513; }","duration":"837.487636ms","start":"2026-02-01T18:49:55.842927Z","end":"2026-02-01T18:49:56.680415Z","steps":["trace[1666389753] 'agreement among raft nodes before linearized reading' (duration: 837.405235ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:56.680448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:49:55.842914Z","time spent":"837.527616ms","remote":"127.0.0.1:56928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4357,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-02-01T18:49:56.680596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.189636219s","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-01T18:49:56.680623Z","caller":"traceutil/trace.go:171","msg":"trace[253921791] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:513; }","duration":"1.18969005s","start":"2026-02-01T18:49:55.490925Z","end":"2026-02-01T18:49:56.680615Z","steps":["trace[253921791] 'agreement among raft nodes before linearized reading' (duration: 1.18962054s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:56.680646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:49:55.490911Z","time spent":"1.18972878s","remote":"127.0.0.1:57148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T18:49:56.680824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.237435776s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-01T18:49:56.680854Z","caller":"traceutil/trace.go:171","msg":"trace[245905073] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:513; }","duration":"1.237490276s","start":"2026-02-01T18:49:55.443354Z","end":"2026-02-01T18:49:56.680845Z","steps":["trace[245905073] 'agreement among raft nodes before linearized reading' (duration: 1.237404985s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:56.680881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:49:55.443341Z","time spent":"1.237533186s","remote":"127.0.0.1:56928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9344,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-01T18:49:56.681039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.43781855s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-01T18:49:56.681068Z","caller":"traceutil/trace.go:171","msg":"trace[1366457122] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:513; }","duration":"1.43785741s","start":"2026-02-01T18:49:55.243202Z","end":"2026-02-01T18:49:56.68106Z","steps":["trace[1366457122] 'agreement among raft nodes before linearized reading' (duration: 1.437772749s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:56.681093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:49:55.243197Z","time spent":"1.43788922s","remote":"127.0.0.1:56928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5610,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-02-01T18:49:56.68126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.452678063s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:49:56.68201Z","caller":"traceutil/trace.go:171","msg":"trace[128431954] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"280.463937ms","start":"2026-02-01T18:49:56.401535Z","end":"2026-02-01T18:49:56.681999Z","steps":["trace[128431954] 'process raft request' (duration: 279.874692ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:49:56.6822Z","caller":"traceutil/trace.go:171","msg":"trace[1346991111] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"276.545185ms","start":"2026-02-01T18:49:56.405645Z","end":"2026-02-01T18:49:56.68219Z","steps":["trace[1346991111] 'process raft request' (duration: 275.834658ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:49:56.682307Z","caller":"traceutil/trace.go:171","msg":"trace[1960653798] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"190.561605ms","start":"2026-02-01T18:49:56.491737Z","end":"2026-02-01T18:49:56.682299Z","steps":["trace[1960653798] 'process raft request' (duration: 189.94604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:56.683003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.552974166s","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-01T18:49:56.683037Z","caller":"traceutil/trace.go:171","msg":"trace[1802057670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:516; }","duration":"1.553032596s","start":"2026-02-01T18:49:55.129995Z","end":"2026-02-01T18:49:56.683027Z","steps":["trace[1802057670] 'agreement among raft nodes before linearized reading' (duration: 1.552974106s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:56.683063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:49:55.129971Z","time spent":"1.553085157s","remote":"127.0.0.1:57148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-01T18:49:56.684155Z","caller":"traceutil/trace.go:171","msg":"trace[1630123131] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:513; }","duration":"1.452763634s","start":"2026-02-01T18:49:55.228517Z","end":"2026-02-01T18:49:56.681281Z","steps":["trace[1630123131] 'agreement among raft nodes before linearized reading' (duration: 1.452689763s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:56.684183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:49:55.228504Z","time spent":"1.455669447s","remote":"127.0.0.1:56966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"warn","ts":"2026-02-01T18:50:02.264265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064753154s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:50:02.264998Z","caller":"traceutil/trace.go:171","msg":"trace[1436282199] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"1.515465068s","start":"2026-02-01T18:50:00.749501Z","end":"2026-02-01T18:50:02.264966Z","steps":["trace[1436282199] 'process raft request' (duration: 449.945937ms)","trace[1436282199] 'compare' (duration: 1.064512793s)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:50:02.265135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:00.749492Z","time spent":"1.515571009s","remote":"127.0.0.1:57148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:50:02.266379Z","caller":"traceutil/trace.go:171","msg":"trace[2042510298] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:582; }","duration":"1.496613925s","start":"2026-02-01T18:50:00.769749Z","end":"2026-02-01T18:50:02.266363Z","steps":["trace[2042510298] 'read index received' (duration: 429.742313ms)","trace[2042510298] 'applied index is now lower than readState.Index' (duration: 1.066870062s)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:50:02.375488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.137666ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-01T18:50:02.375559Z","caller":"traceutil/trace.go:171","msg":"trace[2099869787] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:583; }","duration":"108.876558ms","start":"2026-02-01T18:50:02.266671Z","end":"2026-02-01T18:50:02.375548Z","steps":["trace[2099869787] 'read index received' (duration: 98.801µs)","trace[2099869787] 'applied index is now lower than readState.Index' (duration: 108.777097ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:50:02.375655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.453912402s","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-01T18:50:02.375681Z","caller":"traceutil/trace.go:171","msg":"trace[562777802] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:560; }","duration":"1.453964532s","start":"2026-02-01T18:50:00.921711Z","end":"2026-02-01T18:50:02.375675Z","steps":["trace[562777802] 'agreement among raft nodes before linearized reading' (duration: 1.453865441s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:02.375702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:00.921697Z","time spent":"1.454000862s","remote":"127.0.0.1:57148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T18:50:02.375981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.593153ms","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:50:02.376071Z","caller":"traceutil/trace.go:171","msg":"trace[1373053950] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:560; }","duration":"217.713684ms","start":"2026-02-01T18:50:02.15832Z","end":"2026-02-01T18:50:02.376034Z","steps":["trace[1373053950] 'agreement among raft nodes before linearized reading' (duration: 217.445132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:02.376253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.930475ms","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:50:02.376284Z","caller":"traceutil/trace.go:171","msg":"trace[2054295906] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:560; }","duration":"217.969555ms","start":"2026-02-01T18:50:02.158299Z","end":"2026-02-01T18:50:02.376269Z","steps":["trace[2054295906] 'agreement among raft nodes before linearized reading' (duration: 217.929085ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:02.376368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.082006ms","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:50:02.376384Z","caller":"traceutil/trace.go:171","msg":"trace[1665804516] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:560; }","duration":"218.118626ms","start":"2026-02-01T18:50:02.15826Z","end":"2026-02-01T18:50:02.376378Z","steps":["trace[1665804516] 'agreement among raft nodes before linearized reading' (duration: 218.095696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:02.376499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.711669ms","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:50:02.376516Z","caller":"traceutil/trace.go:171","msg":"trace[145350381] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:560; }","duration":"235.735299ms","start":"2026-02-01T18:50:02.140775Z","end":"2026-02-01T18:50:02.376511Z","steps":["trace[145350381] 'agreement among raft nodes before linearized reading' (duration: 235.706469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:02.376613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.838061ms","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:50:02.376631Z","caller":"traceutil/trace.go:171","msg":"trace[1827402260] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:560; }","duration":"235.862991ms","start":"2026-02-01T18:50:02.140762Z","end":"2026-02-01T18:50:02.376625Z","steps":["trace[1827402260] 'agreement among raft nodes before linearized reading' (duration: 235.83465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:02.376787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.034802ms","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:50:02.376802Z","caller":"traceutil/trace.go:171","msg":"trace[460804087] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:560; }","duration":"236.069413ms","start":"2026-02-01T18:50:02.140728Z","end":"2026-02-01T18:50:02.376797Z","steps":["trace[460804087] 'agreement among raft nodes before linearized reading' (duration: 236.043112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:02.376884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.13522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:50:02.37695Z","caller":"traceutil/trace.go:171","msg":"trace[1504545955] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:560; }","duration":"670.16939ms","start":"2026-02-01T18:50:01.706729Z","end":"2026-02-01T18:50:02.376899Z","steps":["trace[1504545955] 'agreement among raft nodes before linearized reading' (duration: 670.13565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:02.376972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:01.706726Z","time spent":"670.240161ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-02-01T18:50:02.377143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.414172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:50:02.377159Z","caller":"traceutil/trace.go:171","msg":"trace[171635252] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:560; }","duration":"670.432072ms","start":"2026-02-01T18:50:01.706722Z","end":"2026-02-01T18:50:02.377155Z","steps":["trace[171635252] 'agreement among raft nodes before linearized reading' (duration: 670.397602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:02.377175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:01.706712Z","time spent":"670.457692ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 "} {"level":"warn","ts":"2026-02-01T18:50:02.377332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.607529159s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-01T18:50:02.377378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.548457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:50:02.377394Z","caller":"traceutil/trace.go:171","msg":"trace[1969432189] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:560; }","duration":"715.584707ms","start":"2026-02-01T18:50:01.661804Z","end":"2026-02-01T18:50:02.377389Z","steps":["trace[1969432189] 'agreement among raft nodes before linearized reading' (duration: 715.563617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:02.377408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:01.661791Z","time spent":"715.614437ms","remote":"127.0.0.1:56868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-01T18:50:02.378614Z","caller":"traceutil/trace.go:171","msg":"trace[2052333721] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:560; }","duration":"1.6076603s","start":"2026-02-01T18:50:00.769715Z","end":"2026-02-01T18:50:02.377375Z","steps":["trace[2052333721] 'agreement among raft nodes before linearized reading' (duration: 1.497038928s)","trace[2052333721] 'range keys from in-memory index tree' (duration: 110.38972ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:50:02.378665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:00.769701Z","time spent":"1.60895173s","remote":"127.0.0.1:57148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T18:50:02.378822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.859814ms","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:50:02.378863Z","caller":"traceutil/trace.go:171","msg":"trace[1191009260] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:560; }","duration":"207.915885ms","start":"2026-02-01T18:50:02.170937Z","end":"2026-02-01T18:50:02.378853Z","steps":["trace[1191009260] 'agreement among raft nodes before linearized reading' (duration: 207.860805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:02.379037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.100437ms","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:50:02.379064Z","caller":"traceutil/trace.go:171","msg":"trace[1310429583] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:560; }","duration":"208.143637ms","start":"2026-02-01T18:50:02.170914Z","end":"2026-02-01T18:50:02.379058Z","steps":["trace[1310429583] 'agreement among raft nodes before linearized reading' (duration: 208.097757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:02.379394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.492179ms","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:50:02.379431Z","caller":"traceutil/trace.go:171","msg":"trace[1792688519] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:560; }","duration":"208.537041ms","start":"2026-02-01T18:50:02.170871Z","end":"2026-02-01T18:50:02.379408Z","steps":["trace[1792688519] 'agreement among raft nodes before linearized reading' (duration: 208.498119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:02.377355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.645924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:50:02.379565Z","caller":"traceutil/trace.go:171","msg":"trace[1621938013] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:560; }","duration":"672.870982ms","start":"2026-02-01T18:50:01.706686Z","end":"2026-02-01T18:50:02.379557Z","steps":["trace[1621938013] 'agreement among raft nodes before linearized reading' (duration: 670.633943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:02.379624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:01.706673Z","time spent":"672.936522ms","remote":"127.0.0.1:58538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 "} {"level":"warn","ts":"2026-02-01T18:50:02.381634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.218465ms","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:50:02.381724Z","caller":"traceutil/trace.go:171","msg":"trace[182422920] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:560; }","duration":"199.318936ms","start":"2026-02-01T18:50:02.182397Z","end":"2026-02-01T18:50:02.381716Z","steps":["trace[182422920] 'agreement among raft nodes before linearized reading' (duration: 199.209725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:02.381993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.594288ms","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":"warn","ts":"2026-02-01T18:50:02.378152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.785968ms","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:50:02.382072Z","caller":"traceutil/trace.go:171","msg":"trace[674890838] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:560; }","duration":"199.674558ms","start":"2026-02-01T18:50:02.18239Z","end":"2026-02-01T18:50:02.382065Z","steps":["trace[674890838] 'agreement among raft nodes before linearized reading' (duration: 199.581887ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:50:02.382124Z","caller":"traceutil/trace.go:171","msg":"trace[842976779] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:560; }","duration":"199.740429ms","start":"2026-02-01T18:50:02.182346Z","end":"2026-02-01T18:50:02.382086Z","steps":["trace[842976779] 'agreement among raft nodes before linearized reading' (duration: 195.789468ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:50:02.646504Z","caller":"traceutil/trace.go:171","msg":"trace[26586855] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"258.800566ms","start":"2026-02-01T18:50:02.387687Z","end":"2026-02-01T18:50:02.646488Z","steps":["trace[26586855] 'process raft request' (duration: 258.696525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:03.003132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.305963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:50:03.003213Z","caller":"traceutil/trace.go:171","msg":"trace[217929846] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:585; }","duration":"609.408989ms","start":"2026-02-01T18:50:02.393791Z","end":"2026-02-01T18:50:03.0032Z","steps":["trace[217929846] 'read index received' (duration: 252.867217ms)","trace[217929846] 'applied index is now lower than readState.Index' (duration: 356.541052ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:50:03.003447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.555066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-01T18:50:03.003446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.638281ms","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:50:03.0035Z","caller":"traceutil/trace.go:171","msg":"trace[2002786899] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:562; }","duration":"609.713752ms","start":"2026-02-01T18:50:02.393773Z","end":"2026-02-01T18:50:03.003487Z","steps":["trace[2002786899] 'agreement among raft nodes before linearized reading' (duration: 609.550351ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:03.003533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:02.393769Z","time spent":"609.756063ms","remote":"127.0.0.1:57148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-01T18:50:03.003555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.571715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-01T18:50:03.003574Z","caller":"traceutil/trace.go:171","msg":"trace[769002054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:562; }","duration":"262.612555ms","start":"2026-02-01T18:50:02.740956Z","end":"2026-02-01T18:50:03.003569Z","steps":["trace[769002054] 'agreement among raft nodes before linearized reading' (duration: 262.533304ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:50:03.00347Z","caller":"traceutil/trace.go:171","msg":"trace[1768048500] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:562; }","duration":"339.596986ms","start":"2026-02-01T18:50:02.663864Z","end":"2026-02-01T18:50:03.003461Z","steps":["trace[1768048500] 'agreement among raft nodes before linearized reading' (duration: 339.561716ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:03.003651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:02.663849Z","time spent":"339.795298ms","remote":"127.0.0.1:56868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-01T18:50:03.003775Z","caller":"traceutil/trace.go:171","msg":"trace[1608167018] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"610.029934ms","start":"2026-02-01T18:50:02.393733Z","end":"2026-02-01T18:50:03.003763Z","steps":["trace[1608167018] 'process raft request' (duration: 316.024316ms)","trace[1608167018] 'compare' (duration: 293.190932ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:50:03.003813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:02.393713Z","time spent":"610.079375ms","remote":"127.0.0.1:57148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:50:04.769994Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301488623353574,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:50:05.270253Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301488623353574,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:50:05.428709Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.021865889s","expected-duration":"1s"} {"level":"info","ts":"2026-02-01T18:50:05.428934Z","caller":"traceutil/trace.go:171","msg":"trace[590679113] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"1.159458263s","start":"2026-02-01T18:50:04.269462Z","end":"2026-02-01T18:50:05.42892Z","steps":["trace[590679113] 'read index received' (duration: 1.159319596s)","trace[590679113] 'applied index is now lower than readState.Index' (duration: 138.127µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:50:05.429114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.15963195s","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-01T18:50:05.429136Z","caller":"traceutil/trace.go:171","msg":"trace[1145719012] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:568; }","duration":"1.159715562s","start":"2026-02-01T18:50:04.269414Z","end":"2026-02-01T18:50:05.42913Z","steps":["trace[1145719012] 'agreement among raft nodes before linearized reading' (duration: 1.159602197s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:05.429148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.642266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-01T18:50:05.429155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:04.269394Z","time spent":"1.159755783s","remote":"127.0.0.1:57148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-01T18:50:05.429164Z","caller":"traceutil/trace.go:171","msg":"trace[1378695865] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:568; }","duration":"778.691786ms","start":"2026-02-01T18:50:04.650466Z","end":"2026-02-01T18:50:05.429158Z","steps":["trace[1378695865] 'agreement among raft nodes before linearized reading' (duration: 778.646564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:05.429179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:04.650449Z","time spent":"778.726338ms","remote":"127.0.0.1:57148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T18:50:05.429277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.870303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-01T18:50:05.429278Z","caller":"traceutil/trace.go:171","msg":"trace[1087791583] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"1.187091645s","start":"2026-02-01T18:50:04.242181Z","end":"2026-02-01T18:50:05.429273Z","steps":["trace[1087791583] 'process raft request' (duration: 1.186647354s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:05.429339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.9684ms","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:50:05.429352Z","caller":"traceutil/trace.go:171","msg":"trace[1002787757] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:568; }","duration":"392.013751ms","start":"2026-02-01T18:50:05.037333Z","end":"2026-02-01T18:50:05.429347Z","steps":["trace[1002787757] 'agreement among raft nodes before linearized reading' (duration: 391.97946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:05.429351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:04.242161Z","time spent":"1.187156068s","remote":"127.0.0.1:57060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:50:05.429365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:05.037315Z","time spent":"392.047733ms","remote":"127.0.0.1:57148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-01T18:50:05.429305Z","caller":"traceutil/trace.go:171","msg":"trace[1684265746] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:568; }","duration":"323.927195ms","start":"2026-02-01T18:50:05.105373Z","end":"2026-02-01T18:50:05.4293Z","steps":["trace[1684265746] 'agreement among raft nodes before linearized reading' (duration: 323.890113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:05.429418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:05.105349Z","time spent":"324.066302ms","remote":"127.0.0.1:57148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T18:50:05.429455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.003332ms","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:50:05.429466Z","caller":"traceutil/trace.go:171","msg":"trace[1581052444] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:568; }","duration":"417.047534ms","start":"2026-02-01T18:50:05.012415Z","end":"2026-02-01T18:50:05.429463Z","steps":["trace[1581052444] 'agreement among raft nodes before linearized reading' (duration: 417.013593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:05.429478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:05.012396Z","time spent":"417.078826ms","remote":"127.0.0.1:57148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T18:50:05.429563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.364968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:50:05.429577Z","caller":"traceutil/trace.go:171","msg":"trace[161002256] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"766.410448ms","start":"2026-02-01T18:50:04.663162Z","end":"2026-02-01T18:50:05.429572Z","steps":["trace[161002256] 'agreement among raft nodes before linearized reading' (duration: 766.388827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:05.42959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:04.663147Z","time spent":"766.440919ms","remote":"127.0.0.1:56870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-01T18:50:06.125331Z","caller":"traceutil/trace.go:171","msg":"trace[1287532899] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"688.203523ms","start":"2026-02-01T18:50:05.436983Z","end":"2026-02-01T18:50:06.125186Z","steps":["trace[1287532899] 'process raft request' (duration: 670.230495ms)","trace[1287532899] 'compare' (duration: 17.397061ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:50:06.125428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:05.436964Z","time spent":"688.413572ms","remote":"127.0.0.1:57148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:50:06.134644Z","caller":"traceutil/trace.go:171","msg":"trace[1749145191] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:592; }","duration":"463.561743ms","start":"2026-02-01T18:50:05.671067Z","end":"2026-02-01T18:50:06.134629Z","steps":["trace[1749145191] 'read index received' (duration: 436.213019ms)","trace[1749145191] 'applied index is now lower than readState.Index' (duration: 27.347814ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:50:06.13472Z","caller":"traceutil/trace.go:171","msg":"trace[1686669584] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"697.516566ms","start":"2026-02-01T18:50:05.437196Z","end":"2026-02-01T18:50:06.134713Z","steps":["trace[1686669584] 'process raft request' (duration: 697.287956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:06.134784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:05.437187Z","time spent":"697.551158ms","remote":"127.0.0.1:57148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:50:06.134789Z","caller":"traceutil/trace.go:171","msg":"trace[771504038] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"690.298023ms","start":"2026-02-01T18:50:05.444483Z","end":"2026-02-01T18:50:06.134781Z","steps":["trace[771504038] 'process raft request' (duration: 690.123995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:06.134828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:05.444477Z","time spent":"690.328965ms","remote":"127.0.0.1:57148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:50:06.134918Z","caller":"traceutil/trace.go:171","msg":"trace[1491325418] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"693.425537ms","start":"2026-02-01T18:50:05.441444Z","end":"2026-02-01T18:50:06.13487Z","steps":["trace[1491325418] 'process raft request' (duration: 693.115283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:06.134954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:05.441432Z","time spent":"693.502801ms","remote":"127.0.0.1:57148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:50:06.134972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.908469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:50:06.134997Z","caller":"traceutil/trace.go:171","msg":"trace[303148423] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:573; }","duration":"463.960861ms","start":"2026-02-01T18:50:05.671029Z","end":"2026-02-01T18:50:06.13499Z","steps":["trace[303148423] 'agreement among raft nodes before linearized reading' (duration: 463.919989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:06.135019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:05.670996Z","time spent":"464.017105ms","remote":"127.0.0.1:56868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-01T18:50:06.135036Z","caller":"traceutil/trace.go:171","msg":"trace[1450608465] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"692.439152ms","start":"2026-02-01T18:50:05.44259Z","end":"2026-02-01T18:50:06.135029Z","steps":["trace[1450608465] 'process raft request' (duration: 691.997393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:06.13507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:05.442582Z","time spent":"692.467603ms","remote":"127.0.0.1:57148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:50:06.135115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.211071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:50:06.135134Z","caller":"traceutil/trace.go:171","msg":"trace[1542475278] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:573; }","duration":"462.255633ms","start":"2026-02-01T18:50:05.672872Z","end":"2026-02-01T18:50:06.135128Z","steps":["trace[1542475278] 'agreement among raft nodes before linearized reading' (duration: 462.226562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:06.135151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:50:05.672863Z","time spent":"462.283665ms","remote":"127.0.0.1:56870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-01T18:50:22.390073Z","caller":"traceutil/trace.go:171","msg":"trace[1008834414] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"134.968987ms","start":"2026-02-01T18:50:22.255062Z","end":"2026-02-01T18:50:22.390031Z","steps":["trace[1008834414] 'process raft request' (duration: 133.610285ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:50:22.388862Z","caller":"traceutil/trace.go:171","msg":"trace[1246479179] linearizableReadLoop","detail":"{readStateIndex:739; appliedIndex:738; }","duration":"129.550741ms","start":"2026-02-01T18:50:22.259291Z","end":"2026-02-01T18:50:22.388841Z","steps":["trace[1246479179] 'read index received' (duration: 129.25754ms)","trace[1246479179] 'applied index is now lower than readState.Index' (duration: 292.271µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:50:22.390693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.207584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-01T18:50:22.391042Z","caller":"traceutil/trace.go:171","msg":"trace[2133885557] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:709; }","duration":"131.61345ms","start":"2026-02-01T18:50:22.259263Z","end":"2026-02-01T18:50:22.390877Z","steps":["trace[2133885557] 'agreement among raft nodes before linearized reading' (duration: 130.909903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:22.391443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.681703ms","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:50:22.391487Z","caller":"traceutil/trace.go:171","msg":"trace[869036260] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:709; }","duration":"126.742054ms","start":"2026-02-01T18:50:22.264733Z","end":"2026-02-01T18:50:22.391475Z","steps":["trace[869036260] 'agreement among raft nodes before linearized reading' (duration: 126.61801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:22.391895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.02437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-01T18:50:22.391948Z","caller":"traceutil/trace.go:171","msg":"trace[2086198583] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:709; }","duration":"125.085731ms","start":"2026-02-01T18:50:22.266849Z","end":"2026-02-01T18:50:22.391935Z","steps":["trace[2086198583] 'agreement among raft nodes before linearized reading' (duration: 124.966227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:50:22.392185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.341321ms","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-01T18:50:22.392209Z","caller":"traceutil/trace.go:171","msg":"trace[1149055199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:709; }","duration":"125.381313ms","start":"2026-02-01T18:50:22.266821Z","end":"2026-02-01T18:50:22.392203Z","steps":["trace[1149055199] 'agreement among raft nodes before linearized reading' (duration: 125.30173ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:50:28.589565Z","caller":"traceutil/trace.go:171","msg":"trace[958484344] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"140.589754ms","start":"2026-02-01T18:50:28.448952Z","end":"2026-02-01T18:50:28.589541Z","steps":["trace[958484344] 'process raft request' (duration: 140.421337ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:50:28.591164Z","caller":"traceutil/trace.go:171","msg":"trace[137703947] linearizableReadLoop","detail":"{readStateIndex:759; appliedIndex:758; }","duration":"134.503878ms","start":"2026-02-01T18:50:28.456644Z","end":"2026-02-01T18:50:28.591147Z","steps":["trace[137703947] 'read index received' (duration: 133.492062ms)","trace[137703947] 'applied index is now lower than readState.Index' (duration: 1.011126ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:50:28.59132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.652723ms","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:50:28.59134Z","caller":"traceutil/trace.go:171","msg":"trace[333215486] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:730; }","duration":"134.729475ms","start":"2026-02-01T18:50:28.456604Z","end":"2026-02-01T18:50:28.591334Z","steps":["trace[333215486] 'agreement among raft nodes before linearized reading' (duration: 134.601991ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:50:28.591578Z","caller":"traceutil/trace.go:171","msg":"trace[879558558] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"139.717033ms","start":"2026-02-01T18:50:28.451855Z","end":"2026-02-01T18:50:28.591572Z","steps":["trace[879558558] 'process raft request' (duration: 139.134382ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:50:28.591641Z","caller":"traceutil/trace.go:171","msg":"trace[949371082] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"130.303638ms","start":"2026-02-01T18:50:28.461333Z","end":"2026-02-01T18:50:28.591637Z","steps":["trace[949371082] 'process raft request' (duration: 129.771239ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:50:28.591691Z","caller":"traceutil/trace.go:171","msg":"trace[907722726] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"129.349744ms","start":"2026-02-01T18:50:28.462337Z","end":"2026-02-01T18:50:28.591687Z","steps":["trace[907722726] 'process raft request' (duration: 128.785924ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:51:33.121333Z","caller":"traceutil/trace.go:171","msg":"trace[1976198657] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"123.369967ms","start":"2026-02-01T18:51:32.997893Z","end":"2026-02-01T18:51:33.121263Z","steps":["trace[1976198657] 'process raft request' (duration: 123.182573ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:51:33.121493Z","caller":"traceutil/trace.go:171","msg":"trace[945446252] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"114.367885ms","start":"2026-02-01T18:51:33.007101Z","end":"2026-02-01T18:51:33.121469Z","steps":["trace[945446252] 'process raft request' (duration: 114.10943ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:51:33.121641Z","caller":"traceutil/trace.go:171","msg":"trace[1725861285] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"119.007288ms","start":"2026-02-01T18:51:33.002626Z","end":"2026-02-01T18:51:33.121633Z","steps":["trace[1725861285] 'process raft request' (duration: 118.544859ms)"],"step_count":1}