{"level":"warn","ts":"2026-04-20T10:42:37.734745Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T10:42:37.734837Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169: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.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169: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-04-20T10:42:37.734903Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T10:42:37.734914Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-04-20T10:42:37.734931Z","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-04-20T10:42:37.735551Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-04-20T10:42:37.735629Z","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":8,"max-cpu-available":8,"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.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169: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-04-20T10:42:37.739538Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.71018ms"} {"level":"info","ts":"2026-04-20T10:42:37.754208Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-04-20T10:42:37.754346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T10:42:37.754376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-04-20T10:42:37.75439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T10:42:37.754405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-04-20T10:42:37.754502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-04-20T10:42:37.762983Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T10:42:37.767386Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T10:42:37.768497Z","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-04-20T10:42:37.771248Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T10:42:37.771512Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T10:42:37.771729Z","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-04-20T10:42:37.771858Z","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-04-20T10:42:37.771875Z","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-04-20T10:42:37.774247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-04-20T10:42:37.774862Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-04-20T10:42:37.777847Z","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-04-20T10:42:37.77805Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-04-20T10:42:37.778125Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-04-20T10:42:37.778373Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T10:42:37.778421Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T10:42:38.755537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T10:42:38.755611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T10:42:38.755654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-04-20T10:42:38.755681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-04-20T10:42:38.755697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-04-20T10:42:38.755743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-04-20T10:42:38.755763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-04-20T10:42:38.756686Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T10:42:38.756689Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T10:42:38.756703Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T10:42:38.756879Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:42:38.757139Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T10:42:38.757319Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T10:42:38.757699Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:42:38.75782Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:42:38.757854Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:42:38.760102Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T10:42:38.760338Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"warn","ts":"2026-04-20T10:42:41.35698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.875532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:42:41.35709Z","caller":"traceutil/trace.go:171","msg":"trace[1515476325] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver; range_end:; response_count:0; response_revision:139; }","duration":"144.052598ms","start":"2026-04-20T10:42:41.213013Z","end":"2026-04-20T10:42:41.357066Z","steps":["trace[1515476325] 'range keys from in-memory index tree' (duration: 143.78504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:42:49.190808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.757069ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:42:49.19089Z","caller":"traceutil/trace.go:171","msg":"trace[1127091641] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"690.593351ms","start":"2026-04-20T10:42:48.500286Z","end":"2026-04-20T10:42:49.190879Z","steps":["trace[1127091641] 'process raft request' (duration: 400.121929ms)","trace[1127091641] 'compare' (duration: 289.395357ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:42:49.190922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:42:48.500266Z","time spent":"690.643153ms","remote":"127.0.0.1:56516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T10:42:49.655411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.457457ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:42:49.65562Z","caller":"traceutil/trace.go:171","msg":"trace[1270301100] linearizableReadLoop","detail":"{readStateIndex:361; appliedIndex:358; }","duration":"1.13982167s","start":"2026-04-20T10:42:48.515777Z","end":"2026-04-20T10:42:49.655598Z","steps":["trace[1270301100] 'read index received' (duration: 384.671812ms)","trace[1270301100] 'applied index is now lower than readState.Index' (duration: 755.148598ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:42:49.655748Z","caller":"traceutil/trace.go:171","msg":"trace[423095205] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"1.152616278s","start":"2026-04-20T10:42:48.503118Z","end":"2026-04-20T10:42:49.655734Z","steps":["trace[423095205] 'process raft request' (duration: 1.15239483s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:42:49.655846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:42:48.50309Z","time spent":"1.152702461s","remote":"127.0.0.1:56702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1251,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:42:49.655889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.978939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-04-20T10:42:49.655921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.371122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-04-20T10:42:49.655952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.559368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-20T10:42:49.655968Z","caller":"traceutil/trace.go:171","msg":"trace[1348601167] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:348; }","duration":"939.446454ms","start":"2026-04-20T10:42:48.716509Z","end":"2026-04-20T10:42:49.655956Z","steps":["trace[1348601167] 'agreement among raft nodes before linearized reading' (duration: 939.365221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:42:49.655991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.636165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4197"} {"level":"warn","ts":"2026-04-20T10:42:49.655956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.712376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-20T10:42:49.656031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.140252264s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-04-20T10:42:49.656051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.319863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-20T10:42:49.656052Z","caller":"traceutil/trace.go:171","msg":"trace[369794366] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:348; }","duration":"727.701938ms","start":"2026-04-20T10:42:48.928335Z","end":"2026-04-20T10:42:49.656036Z","steps":["trace[369794366] 'agreement among raft nodes before linearized reading' (duration: 727.590134ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:42:49.656002Z","caller":"traceutil/trace.go:171","msg":"trace[75803598] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:348; }","duration":"990.661161ms","start":"2026-04-20T10:42:48.665324Z","end":"2026-04-20T10:42:49.655986Z","steps":["trace[75803598] 'agreement among raft nodes before linearized reading' (duration: 990.603499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:42:49.656064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040670146s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-04-20T10:42:49.656085Z","caller":"traceutil/trace.go:171","msg":"trace[484031423] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:348; }","duration":"840.375725ms","start":"2026-04-20T10:42:48.815695Z","end":"2026-04-20T10:42:49.656071Z","steps":["trace[484031423] 'agreement among raft nodes before linearized reading' (duration: 840.331513ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:42:49.656095Z","caller":"traceutil/trace.go:171","msg":"trace[1210397742] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:348; }","duration":"1.040709388s","start":"2026-04-20T10:42:48.615377Z","end":"2026-04-20T10:42:49.656087Z","steps":["trace[1210397742] 'agreement among raft nodes before linearized reading' (duration: 1.040666637s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:42:49.656091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:42:48.928277Z","time spent":"727.805791ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4220,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-20T10:42:49.656093Z","caller":"traceutil/trace.go:171","msg":"trace[823245326] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:348; }","duration":"1.140337947s","start":"2026-04-20T10:42:48.515731Z","end":"2026-04-20T10:42:49.656069Z","steps":["trace[823245326] 'agreement among raft nodes before linearized reading' (duration: 1.140213492s)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:42:49.6561Z","caller":"traceutil/trace.go:171","msg":"trace[1547222525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:348; }","duration":"426.886182ms","start":"2026-04-20T10:42:49.229165Z","end":"2026-04-20T10:42:49.656051Z","steps":["trace[1547222525] 'agreement among raft nodes before linearized reading' (duration: 426.682815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:42:49.656124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:42:48.615372Z","time spent":"1.040746179s","remote":"127.0.0.1:56648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-04-20T10:42:49.656143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:42:48.515711Z","time spent":"1.1404205s","remote":"127.0.0.1:56648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-04-20T10:42:49.656156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.539934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"warn","ts":"2026-04-20T10:42:49.656189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.053377973s","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-04-20T10:42:49.655959Z","caller":"traceutil/trace.go:171","msg":"trace[585866914] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:348; }","duration":"791.080602ms","start":"2026-04-20T10:42:48.864863Z","end":"2026-04-20T10:42:49.655943Z","steps":["trace[585866914] 'agreement among raft nodes before linearized reading' (duration: 790.969229ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:42:49.656237Z","caller":"traceutil/trace.go:171","msg":"trace[1952862823] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:348; }","duration":"1.053487378s","start":"2026-04-20T10:42:48.60274Z","end":"2026-04-20T10:42:49.656227Z","steps":["trace[1952862823] 'agreement among raft nodes before linearized reading' (duration: 1.053381783s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:42:49.655884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.089983949s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-04-20T10:42:49.656271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:42:48.602723Z","time spent":"1.053541099s","remote":"127.0.0.1:56708","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-04-20T10:42:49.656248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:42:49.229145Z","time spent":"427.031996ms","remote":"127.0.0.1:56708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T10:42:49.656244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:42:48.864842Z","time spent":"791.394283ms","remote":"127.0.0.1:56648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-04-20T10:42:49.656118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:42:48.815684Z","time spent":"840.428186ms","remote":"127.0.0.1:56648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"info","ts":"2026-04-20T10:42:49.656293Z","caller":"traceutil/trace.go:171","msg":"trace[846730678] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:348; }","duration":"1.090458066s","start":"2026-04-20T10:42:48.565818Z","end":"2026-04-20T10:42:49.656277Z","steps":["trace[846730678] 'agreement among raft nodes before linearized reading' (duration: 1.08998589s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:42:49.656015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.533851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-20T10:42:49.656444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:42:48.565794Z","time spent":"1.090640892s","remote":"127.0.0.1:56648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"info","ts":"2026-04-20T10:42:49.656465Z","caller":"traceutil/trace.go:171","msg":"trace[166117275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:348; }","duration":"739.005367ms","start":"2026-04-20T10:42:48.917446Z","end":"2026-04-20T10:42:49.656451Z","steps":["trace[166117275] 'agreement among raft nodes before linearized reading' (duration: 738.536531ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:42:49.656196Z","caller":"traceutil/trace.go:171","msg":"trace[774408821] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:348; }","duration":"890.622539ms","start":"2026-04-20T10:42:48.765566Z","end":"2026-04-20T10:42:49.656189Z","steps":["trace[774408821] 'agreement among raft nodes before linearized reading' (duration: 890.569156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:42:49.656005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:42:48.716495Z","time spent":"939.502056ms","remote":"127.0.0.1:56648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-04-20T10:42:49.656552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:42:48.765542Z","time spent":"890.999611ms","remote":"127.0.0.1:56648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-04-20T10:42:49.656514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:42:48.917426Z","time spent":"739.07309ms","remote":"127.0.0.1:56708","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-04-20T10:42:49.6561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:42:48.665311Z","time spent":"990.776264ms","remote":"127.0.0.1:56648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"info","ts":"2026-04-20T10:42:49.655823Z","caller":"traceutil/trace.go:171","msg":"trace[597383053] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"1.152835865s","start":"2026-04-20T10:42:48.502954Z","end":"2026-04-20T10:42:49.65579Z","steps":["trace[597383053] 'process raft request' (duration: 902.79021ms)","trace[597383053] 'compare' (duration: 249.129045ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:42:49.65726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:42:48.502945Z","time spent":"1.154249623s","remote":"127.0.0.1:56900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3691,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:43:11.250572Z","caller":"traceutil/trace.go:171","msg":"trace[719537512] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"132.526073ms","start":"2026-04-20T10:43:11.118021Z","end":"2026-04-20T10:43:11.250548Z","steps":["trace[719537512] 'process raft request' (duration: 131.985209ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:43:11.495894Z","caller":"traceutil/trace.go:171","msg":"trace[1233308361] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"191.853275ms","start":"2026-04-20T10:43:11.304017Z","end":"2026-04-20T10:43:11.49587Z","steps":["trace[1233308361] 'process raft request' (duration: 191.728641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:43:12.193301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.984526ms","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-04-20T10:43:12.193473Z","caller":"traceutil/trace.go:171","msg":"trace[241465601] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:608; }","duration":"353.184771ms","start":"2026-04-20T10:43:11.840266Z","end":"2026-04-20T10:43:12.193451Z","steps":["trace[241465601] 'range keys from in-memory index tree' (duration: 352.856253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:43:12.193558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:43:11.840252Z","time spent":"353.283125ms","remote":"127.0.0.1:56708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T10:43:12.193693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.955306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-47z6z\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-04-20T10:43:12.193731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.487894ms","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-04-20T10:43:12.193774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.896219ms","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-04-20T10:43:12.193835Z","caller":"traceutil/trace.go:171","msg":"trace[118429147] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:608; }","duration":"391.611568ms","start":"2026-04-20T10:43:11.802184Z","end":"2026-04-20T10:43:12.193796Z","steps":["trace[118429147] 'range keys from in-memory index tree' (duration: 391.3348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:43:12.193935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.044844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-20T10:43:12.193956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:43:11.802174Z","time spent":"391.763821ms","remote":"127.0.0.1:56708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-20T10:43:12.193988Z","caller":"traceutil/trace.go:171","msg":"trace[1836588011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:608; }","duration":"341.159506ms","start":"2026-04-20T10:43:11.852813Z","end":"2026-04-20T10:43:12.193972Z","steps":["trace[1836588011] 'range keys from in-memory index tree' (duration: 340.878299ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:43:12.193852Z","caller":"traceutil/trace.go:171","msg":"trace[931270965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:608; }","duration":"340.994732ms","start":"2026-04-20T10:43:11.852831Z","end":"2026-04-20T10:43:12.193826Z","steps":["trace[931270965] 'range keys from in-memory index tree' (duration: 340.769337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:43:12.194113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:43:11.852793Z","time spent":"341.29733ms","remote":"127.0.0.1:56708","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-04-20T10:43:12.194201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:43:11.852807Z","time spent":"341.324411ms","remote":"127.0.0.1:56708","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-04-20T10:43:12.193793Z","caller":"traceutil/trace.go:171","msg":"trace[620170154] range","detail":"{range_begin:/registry/pods/kube-system/cilium-47z6z; range_end:; response_count:1; response_revision:608; }","duration":"412.063959ms","start":"2026-04-20T10:43:11.781706Z","end":"2026-04-20T10:43:12.19377Z","steps":["trace[620170154] 'range keys from in-memory index tree' (duration: 411.453471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:43:12.194371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:43:11.781661Z","time spent":"412.693075ms","remote":"127.0.0.1:56636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-47z6z\" "} {"level":"info","ts":"2026-04-20T10:43:12.198312Z","caller":"traceutil/trace.go:171","msg":"trace[536182315] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"245.820515ms","start":"2026-04-20T10:43:11.952464Z","end":"2026-04-20T10:43:12.198285Z","steps":["trace[536182315] 'read index received' (duration: 235.613773ms)","trace[536182315] 'applied index is now lower than readState.Index' (duration: 10.206052ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:43:12.199613Z","caller":"traceutil/trace.go:171","msg":"trace[458860074] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"308.928197ms","start":"2026-04-20T10:43:11.89067Z","end":"2026-04-20T10:43:12.199598Z","steps":["trace[458860074] 'process raft request' (duration: 305.458663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:43:12.199677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.208993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T10:43:12.199738Z","caller":"traceutil/trace.go:171","msg":"trace[1255177694] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:609; }","duration":"247.325985ms","start":"2026-04-20T10:43:11.952398Z","end":"2026-04-20T10:43:12.199724Z","steps":["trace[1255177694] 'agreement among raft nodes before linearized reading' (duration: 247.218062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:43:12.199754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:43:11.890637Z","time spent":"309.04253ms","remote":"127.0.0.1:56538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:44:36.617611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.308356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:44:36.617681Z","caller":"traceutil/trace.go:171","msg":"trace[1059576451] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1093; }","duration":"350.419427ms","start":"2026-04-20T10:44:36.267245Z","end":"2026-04-20T10:44:36.617665Z","steps":["trace[1059576451] 'range keys from in-memory index tree' (duration: 350.213395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:44:36.617713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:44:36.267227Z","time spent":"350.478339ms","remote":"127.0.0.1:56432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T10:44:36.618056Z","caller":"traceutil/trace.go:171","msg":"trace[2129204671] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"343.546205ms","start":"2026-04-20T10:44:36.27448Z","end":"2026-04-20T10:44:36.618027Z","steps":["trace[2129204671] 'process raft request' (duration: 342.453641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:44:36.618265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:44:36.274466Z","time spent":"343.706976ms","remote":"127.0.0.1:56708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:44:58.830616Z","caller":"traceutil/trace.go:171","msg":"trace[1218199626] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"127.697838ms","start":"2026-04-20T10:44:58.702882Z","end":"2026-04-20T10:44:58.83058Z","steps":["trace[1218199626] 'process raft request' (duration: 127.479626ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:45:01.036415Z","caller":"traceutil/trace.go:171","msg":"trace[1488790815] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"116.137891ms","start":"2026-04-20T10:45:00.920259Z","end":"2026-04-20T10:45:01.036397Z","steps":["trace[1488790815] 'process raft request' (duration: 115.636375ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:45:01.03641Z","caller":"traceutil/trace.go:171","msg":"trace[1121288135] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"116.390934ms","start":"2026-04-20T10:45:00.919969Z","end":"2026-04-20T10:45:01.03636Z","steps":["trace[1121288135] 'process raft request' (duration: 63.132265ms)","trace[1121288135] 'compare' (duration: 52.611521ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:45:01.176307Z","caller":"traceutil/trace.go:171","msg":"trace[41383526] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"134.563781ms","start":"2026-04-20T10:45:01.041716Z","end":"2026-04-20T10:45:01.17628Z","steps":["trace[41383526] 'process raft request' (duration: 97.416416ms)","trace[41383526] 'compare' (duration: 37.034594ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:45:01.20386Z","caller":"traceutil/trace.go:171","msg":"trace[939590781] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"161.904409ms","start":"2026-04-20T10:45:01.041915Z","end":"2026-04-20T10:45:01.20382Z","steps":["trace[939590781] 'process raft request' (duration: 161.684656ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:45:57.71497Z","caller":"traceutil/trace.go:171","msg":"trace[1951184649] linearizableReadLoop","detail":"{readStateIndex:1420; appliedIndex:1419; }","duration":"275.940344ms","start":"2026-04-20T10:45:57.439003Z","end":"2026-04-20T10:45:57.714943Z","steps":["trace[1951184649] 'read index received' (duration: 275.662247ms)","trace[1951184649] 'applied index is now lower than readState.Index' (duration: 277.027µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:45:57.715043Z","caller":"traceutil/trace.go:171","msg":"trace[860370047] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"283.98866ms","start":"2026-04-20T10:45:57.431025Z","end":"2026-04-20T10:45:57.715014Z","steps":["trace[860370047] 'process raft request' (duration: 283.719792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:45:57.715239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.214092ms","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-04-20T10:45:57.715287Z","caller":"traceutil/trace.go:171","msg":"trace[1223127480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1361; }","duration":"276.339496ms","start":"2026-04-20T10:45:57.438933Z","end":"2026-04-20T10:45:57.715273Z","steps":["trace[1223127480] 'agreement among raft nodes before linearized reading' (duration: 276.159451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:45:57.715385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.070275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:45:57.715524Z","caller":"traceutil/trace.go:171","msg":"trace[1070746917] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1361; }","duration":"274.260831ms","start":"2026-04-20T10:45:57.441233Z","end":"2026-04-20T10:45:57.715494Z","steps":["trace[1070746917] 'agreement among raft nodes before linearized reading' (duration: 273.989694ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:45:59.016551Z","caller":"traceutil/trace.go:171","msg":"trace[206964736] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"635.125618ms","start":"2026-04-20T10:45:58.381396Z","end":"2026-04-20T10:45:59.016522Z","steps":["trace[206964736] 'process raft request' (duration: 634.954493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:45:59.016742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:45:58.381372Z","time spent":"635.261872ms","remote":"127.0.0.1:56708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:47:07.82245Z","caller":"traceutil/trace.go:171","msg":"trace[1957434864] transaction","detail":"{read_only:false; response_revision:1589; number_of_response:1; }","duration":"192.494778ms","start":"2026-04-20T10:47:07.629928Z","end":"2026-04-20T10:47:07.822423Z","steps":["trace[1957434864] 'process raft request' (duration: 192.310294ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:07.82345Z","caller":"traceutil/trace.go:171","msg":"trace[1545922852] transaction","detail":"{read_only:false; response_revision:1590; number_of_response:1; }","duration":"191.999396ms","start":"2026-04-20T10:47:07.631428Z","end":"2026-04-20T10:47:07.823428Z","steps":["trace[1545922852] 'process raft request' (duration: 191.908394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:08.767458Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828108998953071,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:47:09.267864Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828108998953071,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:47:09.563254Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.422448542s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T10:47:09.567821Z","caller":"traceutil/trace.go:171","msg":"trace[213758567] transaction","detail":"{read_only:false; response_revision:1592; number_of_response:1; }","duration":"1.427113251s","start":"2026-04-20T10:47:08.140685Z","end":"2026-04-20T10:47:09.567798Z","steps":["trace[213758567] 'process raft request' (duration: 1.422840882s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:09.567957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:08.140656Z","time spent":"1.427229584s","remote":"127.0.0.1:56708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:47:09.567962Z","caller":"traceutil/trace.go:171","msg":"trace[616388058] transaction","detail":"{read_only:false; response_revision:1593; number_of_response:1; }","duration":"518.858581ms","start":"2026-04-20T10:47:09.049092Z","end":"2026-04-20T10:47:09.56795Z","steps":["trace[616388058] 'process raft request' (duration: 518.637935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:09.568017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:09.049064Z","time spent":"518.925083ms","remote":"127.0.0.1:56708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:47:09.568155Z","caller":"traceutil/trace.go:171","msg":"trace[1397142024] linearizableReadLoop","detail":"{readStateIndex:1666; appliedIndex:1664; }","duration":"1.301454883s","start":"2026-04-20T10:47:08.266689Z","end":"2026-04-20T10:47:09.568144Z","steps":["trace[1397142024] 'read index received' (duration: 1.296848087s)","trace[1397142024] 'applied index is now lower than readState.Index' (duration: 4.605976ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:47:09.568245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.301560626s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:47:09.568208Z","caller":"traceutil/trace.go:171","msg":"trace[184695694] transaction","detail":"{read_only:false; response_revision:1594; number_of_response:1; }","duration":"163.508096ms","start":"2026-04-20T10:47:09.404658Z","end":"2026-04-20T10:47:09.568166Z","steps":["trace[184695694] 'process raft request' (duration: 163.25866ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:09.568267Z","caller":"traceutil/trace.go:171","msg":"trace[1847531195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1594; }","duration":"1.301623267s","start":"2026-04-20T10:47:08.266638Z","end":"2026-04-20T10:47:09.568261Z","steps":["trace[1847531195] 'agreement among raft nodes before linearized reading' (duration: 1.301564776s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:09.568286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:08.266617Z","time spent":"1.301664998s","remote":"127.0.0.1:56432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T10:47:09.568402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.069151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:47:09.568437Z","caller":"traceutil/trace.go:171","msg":"trace[194179761] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1594; }","duration":"104.127143ms","start":"2026-04-20T10:47:09.464297Z","end":"2026-04-20T10:47:09.568424Z","steps":["trace[194179761] 'agreement among raft nodes before linearized reading' (duration: 104.057822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:09.568438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.728593ms","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-04-20T10:47:09.56848Z","caller":"traceutil/trace.go:171","msg":"trace[325161011] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1594; }","duration":"156.798465ms","start":"2026-04-20T10:47:09.411668Z","end":"2026-04-20T10:47:09.568466Z","steps":["trace[325161011] 'agreement among raft nodes before linearized reading' (duration: 156.662302ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:11.753777Z","caller":"traceutil/trace.go:171","msg":"trace[931190301] transaction","detail":"{read_only:false; response_revision:1599; number_of_response:1; }","duration":"179.629583ms","start":"2026-04-20T10:47:11.574122Z","end":"2026-04-20T10:47:11.753751Z","steps":["trace[931190301] 'process raft request' (duration: 179.486929ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:11.756886Z","caller":"traceutil/trace.go:171","msg":"trace[246492286] linearizableReadLoop","detail":"{readStateIndex:1673; appliedIndex:1671; }","duration":"177.904368ms","start":"2026-04-20T10:47:11.578954Z","end":"2026-04-20T10:47:11.756858Z","steps":["trace[246492286] 'read index received' (duration: 174.631956ms)","trace[246492286] 'applied index is now lower than readState.Index' (duration: 3.269352ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:47:11.757653Z","caller":"traceutil/trace.go:171","msg":"trace[1208486883] transaction","detail":"{read_only:false; response_revision:1600; number_of_response:1; }","duration":"181.216572ms","start":"2026-04-20T10:47:11.576167Z","end":"2026-04-20T10:47:11.757383Z","steps":["trace[1208486883] 'process raft request' (duration: 180.579606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:11.759711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.731491ms","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-04-20T10:47:11.7598Z","caller":"traceutil/trace.go:171","msg":"trace[1290084083] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1600; }","duration":"180.838673ms","start":"2026-04-20T10:47:11.578928Z","end":"2026-04-20T10:47:11.759767Z","steps":["trace[1290084083] 'agreement among raft nodes before linearized reading' (duration: 178.045002ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:14.402721Z","caller":"traceutil/trace.go:171","msg":"trace[1934829715] transaction","detail":"{read_only:false; response_revision:1611; number_of_response:1; }","duration":"238.82778ms","start":"2026-04-20T10:47:14.163847Z","end":"2026-04-20T10:47:14.402675Z","steps":["trace[1934829715] 'process raft request' (duration: 238.590384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:15.768344Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828108998953125,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:47:16.268914Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828108998953125,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:47:16.292616Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.330426236s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T10:47:16.293088Z","caller":"traceutil/trace.go:171","msg":"trace[1437303856] transaction","detail":"{read_only:false; response_revision:1612; number_of_response:1; }","duration":"1.330966739s","start":"2026-04-20T10:47:14.962094Z","end":"2026-04-20T10:47:16.293061Z","steps":["trace[1437303856] 'process raft request' (duration: 1.330741433s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:16.293288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:14.962071Z","time spent":"1.331088062s","remote":"127.0.0.1:56708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:47:16.351722Z","caller":"traceutil/trace.go:171","msg":"trace[1916948143] linearizableReadLoop","detail":"{readStateIndex:1687; appliedIndex:1685; }","duration":"1.083959573s","start":"2026-04-20T10:47:15.267737Z","end":"2026-04-20T10:47:16.351697Z","steps":["trace[1916948143] 'read index received' (duration: 1.025083464s)","trace[1916948143] 'applied index is now lower than readState.Index' (duration: 58.875259ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:47:16.351844Z","caller":"traceutil/trace.go:171","msg":"trace[543098343] transaction","detail":"{read_only:false; response_revision:1613; number_of_response:1; }","duration":"585.441935ms","start":"2026-04-20T10:47:15.766365Z","end":"2026-04-20T10:47:16.351807Z","steps":["trace[543098343] 'process raft request' (duration: 584.364599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:16.35189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.084129827s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:47:16.35193Z","caller":"traceutil/trace.go:171","msg":"trace[1926050792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1613; }","duration":"1.084215839s","start":"2026-04-20T10:47:15.267701Z","end":"2026-04-20T10:47:16.351917Z","steps":["trace[1926050792] 'agreement among raft nodes before linearized reading' (duration: 1.084135197s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:16.351966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:15.267689Z","time spent":"1.08427115s","remote":"127.0.0.1:56432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T10:47:16.352046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.102591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-20T10:47:16.352167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.281264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-20T10:47:16.35223Z","caller":"traceutil/trace.go:171","msg":"trace[700647468] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1613; }","duration":"582.270405ms","start":"2026-04-20T10:47:15.769875Z","end":"2026-04-20T10:47:16.352145Z","steps":["trace[700647468] 'agreement among raft nodes before linearized reading' (duration: 582.038759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:16.352286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:15.769861Z","time spent":"582.401429ms","remote":"127.0.0.1:56708","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-04-20T10:47:16.352299Z","caller":"traceutil/trace.go:171","msg":"trace[591057686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1613; }","duration":"493.405437ms","start":"2026-04-20T10:47:15.85884Z","end":"2026-04-20T10:47:16.352246Z","steps":["trace[591057686] 'agreement among raft nodes before linearized reading' (duration: 493.180512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:16.3523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.855111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-20T10:47:16.352365Z","caller":"traceutil/trace.go:171","msg":"trace[394890534] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1613; }","duration":"499.919843ms","start":"2026-04-20T10:47:15.852426Z","end":"2026-04-20T10:47:16.352346Z","steps":["trace[394890534] 'agreement among raft nodes before linearized reading' (duration: 499.725747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:16.352418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:15.852389Z","time spent":"500.016285ms","remote":"127.0.0.1:56708","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-04-20T10:47:16.352395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:15.858832Z","time spent":"493.545143ms","remote":"127.0.0.1:56708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T10:47:16.352501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.810595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:47:16.35244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.778231ms","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-04-20T10:47:16.352584Z","caller":"traceutil/trace.go:171","msg":"trace[672377517] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1613; }","duration":"573.957545ms","start":"2026-04-20T10:47:15.778613Z","end":"2026-04-20T10:47:16.352571Z","steps":["trace[672377517] 'agreement among raft nodes before linearized reading' (duration: 573.718779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:16.352675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:15.77859Z","time spent":"574.031677ms","remote":"127.0.0.1:56708","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-04-20T10:47:16.352542Z","caller":"traceutil/trace.go:171","msg":"trace[1679004658] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1613; }","duration":"466.876197ms","start":"2026-04-20T10:47:15.885656Z","end":"2026-04-20T10:47:16.352532Z","steps":["trace[1679004658] 'agreement among raft nodes before linearized reading' (duration: 466.815266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:16.35276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:15.885645Z","time spent":"467.103493ms","remote":"127.0.0.1:48146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:47:16.353088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:15.766342Z","time spent":"585.579809ms","remote":"127.0.0.1:56708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:48:25.100282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.782874841s","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-04-20T10:48:25.100362Z","caller":"traceutil/trace.go:171","msg":"trace[15752639] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1837; }","duration":"1.783010315s","start":"2026-04-20T10:48:23.317332Z","end":"2026-04-20T10:48:25.100342Z","steps":["trace[15752639] 'count revisions from in-memory index tree' (duration: 1.782763359s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:25.100411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:23.317308Z","time spent":"1.783093517s","remote":"127.0.0.1:48206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:48:25.100538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.125356236s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:48:25.100608Z","caller":"traceutil/trace.go:171","msg":"trace[309092490] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1837; }","duration":"1.125440508s","start":"2026-04-20T10:48:23.97515Z","end":"2026-04-20T10:48:25.100591Z","steps":["trace[309092490] 'range keys from in-memory index tree' (duration: 1.125259104s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:25.100648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:23.975093Z","time spent":"1.12554751s","remote":"127.0.0.1:56432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T10:48:25.100805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.801984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:48:25.100919Z","caller":"traceutil/trace.go:171","msg":"trace[595062477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1837; }","duration":"832.951719ms","start":"2026-04-20T10:48:24.267949Z","end":"2026-04-20T10:48:25.100901Z","steps":["trace[595062477] 'range keys from in-memory index tree' (duration: 832.747402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:25.100957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:24.267936Z","time spent":"833.01226ms","remote":"127.0.0.1:56446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T10:49:09.572434Z","caller":"traceutil/trace.go:171","msg":"trace[1641953397] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"671.077871ms","start":"2026-04-20T10:49:08.901318Z","end":"2026-04-20T10:49:09.572396Z","steps":["trace[1641953397] 'process raft request' (duration: 670.845175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:49:09.572756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:49:08.901298Z","time spent":"671.253045ms","remote":"127.0.0.1:56708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:49:09.711534Z","caller":"traceutil/trace.go:171","msg":"trace[1774867885] linearizableReadLoop","detail":"{readStateIndex:2081; appliedIndex:2079; }","duration":"277.700636ms","start":"2026-04-20T10:49:09.433794Z","end":"2026-04-20T10:49:09.711495Z","steps":["trace[1774867885] 'read index received' (duration: 138.389941ms)","trace[1774867885] 'applied index is now lower than readState.Index' (duration: 139.308955ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:49:09.711611Z","caller":"traceutil/trace.go:171","msg":"trace[930356024] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"284.113575ms","start":"2026-04-20T10:49:09.42745Z","end":"2026-04-20T10:49:09.711564Z","steps":["trace[930356024] 'process raft request' (duration: 268.477497ms)","trace[930356024] 'compare' (duration: 15.447683ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:49:09.711827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.117995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-20T10:49:09.711877Z","caller":"traceutil/trace.go:171","msg":"trace[1656264599] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1984; }","duration":"265.220467ms","start":"2026-04-20T10:49:09.446642Z","end":"2026-04-20T10:49:09.711862Z","steps":["trace[1656264599] 'agreement among raft nodes before linearized reading' (duration: 265.031303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:49:09.711859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.042194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-20T10:49:09.711928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.804776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T10:49:09.71195Z","caller":"traceutil/trace.go:171","msg":"trace[546085531] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1984; }","duration":"278.191698ms","start":"2026-04-20T10:49:09.433732Z","end":"2026-04-20T10:49:09.711923Z","steps":["trace[546085531] 'agreement among raft nodes before linearized reading' (duration: 277.906011ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:49:09.711979Z","caller":"traceutil/trace.go:171","msg":"trace[1031871481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1984; }","duration":"187.93157ms","start":"2026-04-20T10:49:09.524034Z","end":"2026-04-20T10:49:09.711966Z","steps":["trace[1031871481] 'agreement among raft nodes before linearized reading' (duration: 187.795696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:49:09.712032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.388486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-20T10:49:09.71207Z","caller":"traceutil/trace.go:171","msg":"trace[959906016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1984; }","duration":"187.51976ms","start":"2026-04-20T10:49:09.52454Z","end":"2026-04-20T10:49:09.71206Z","steps":["trace[959906016] 'agreement among raft nodes before linearized reading' (duration: 187.276994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:49:09.712127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.214997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-20T10:49:09.712148Z","caller":"traceutil/trace.go:171","msg":"trace[2101985502] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1984; }","duration":"188.268218ms","start":"2026-04-20T10:49:09.523873Z","end":"2026-04-20T10:49:09.712142Z","steps":["trace[2101985502] 'agreement among raft nodes before linearized reading' (duration: 188.202376ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:49:34.200387Z","caller":"traceutil/trace.go:171","msg":"trace[709418288] linearizableReadLoop","detail":"{readStateIndex:2167; appliedIndex:2166; }","duration":"301.080577ms","start":"2026-04-20T10:49:33.899279Z","end":"2026-04-20T10:49:34.20036Z","steps":["trace[709418288] 'read index received' (duration: 257.909066ms)","trace[709418288] 'applied index is now lower than readState.Index' (duration: 43.170261ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:49:34.200554Z","caller":"traceutil/trace.go:171","msg":"trace[371444358] transaction","detail":"{read_only:false; response_revision:2065; number_of_response:1; }","duration":"326.975798ms","start":"2026-04-20T10:49:33.873558Z","end":"2026-04-20T10:49:34.200534Z","steps":["trace[371444358] 'process raft request' (duration: 283.672924ms)","trace[371444358] 'compare' (duration: 43.018277ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:49:34.200673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.484832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-20T10:49:34.200731Z","caller":"traceutil/trace.go:171","msg":"trace[645746316] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2065; }","duration":"296.575245ms","start":"2026-04-20T10:49:33.904144Z","end":"2026-04-20T10:49:34.20072Z","steps":["trace[645746316] 'agreement among raft nodes before linearized reading' (duration: 296.421011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:49:34.2008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.624875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T10:49:34.200832Z","caller":"traceutil/trace.go:171","msg":"trace[233684809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2065; }","duration":"294.682207ms","start":"2026-04-20T10:49:33.906141Z","end":"2026-04-20T10:49:34.200823Z","steps":["trace[233684809] 'agreement among raft nodes before linearized reading' (duration: 294.597105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:49:34.200683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:49:33.873535Z","time spent":"327.060121ms","remote":"127.0.0.1:56708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:49:34.20085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.698808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-20T10:49:34.200859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.567229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-20T10:49:34.200904Z","caller":"traceutil/trace.go:171","msg":"trace[1829917723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2065; }","duration":"294.77038ms","start":"2026-04-20T10:49:33.906118Z","end":"2026-04-20T10:49:34.200888Z","steps":["trace[1829917723] 'agreement among raft nodes before linearized reading' (duration: 294.641227ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:49:34.200918Z","caller":"traceutil/trace.go:171","msg":"trace[1592730846] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2065; }","duration":"301.661771ms","start":"2026-04-20T10:49:33.899238Z","end":"2026-04-20T10:49:34.2009Z","steps":["trace[1592730846] 'agreement among raft nodes before linearized reading' (duration: 301.506577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:49:34.200963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:49:33.899217Z","time spent":"301.733662ms","remote":"127.0.0.1:56708","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-04-20T10:49:35.975978Z","caller":"traceutil/trace.go:171","msg":"trace[1143131086] transaction","detail":"{read_only:false; response_revision:2070; number_of_response:1; }","duration":"100.589264ms","start":"2026-04-20T10:49:35.875366Z","end":"2026-04-20T10:49:35.975955Z","steps":["trace[1143131086] 'process raft request' (duration: 100.445701ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:49:48.167475Z","caller":"traceutil/trace.go:171","msg":"trace[1534414834] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"153.99553ms","start":"2026-04-20T10:49:48.013456Z","end":"2026-04-20T10:49:48.167451Z","steps":["trace[1534414834] 'process raft request' (duration: 153.831545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:49:48.469048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.45364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:49:48.469114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.31335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T10:49:48.469131Z","caller":"traceutil/trace.go:171","msg":"trace[1944090626] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2160; }","duration":"201.580293ms","start":"2026-04-20T10:49:48.267532Z","end":"2026-04-20T10:49:48.469112Z","steps":["trace[1944090626] 'range keys from in-memory index tree' (duration: 201.373869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:49:48.469118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.208181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-20T10:49:48.469166Z","caller":"traceutil/trace.go:171","msg":"trace[1586518232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2160; }","duration":"186.415592ms","start":"2026-04-20T10:49:48.282737Z","end":"2026-04-20T10:49:48.469153Z","steps":["trace[1586518232] 'range keys from in-memory index tree' (duration: 186.172307ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:49:48.469196Z","caller":"traceutil/trace.go:171","msg":"trace[1865668018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2160; }","duration":"151.337894ms","start":"2026-04-20T10:49:48.31784Z","end":"2026-04-20T10:49:48.469178Z","steps":["trace[1865668018] 'range keys from in-memory index tree' (duration: 150.953815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:49:48.469233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.858111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-20T10:49:48.469249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.823576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-20T10:49:48.469338Z","caller":"traceutil/trace.go:171","msg":"trace[1646317222] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2160; }","duration":"147.988339ms","start":"2026-04-20T10:49:48.32133Z","end":"2026-04-20T10:49:48.469318Z","steps":["trace[1646317222] 'range keys from in-memory index tree' (duration: 147.671401ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:49:48.469285Z","caller":"traceutil/trace.go:171","msg":"trace[1022652342] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2160; }","duration":"209.940212ms","start":"2026-04-20T10:49:48.259334Z","end":"2026-04-20T10:49:48.469274Z","steps":["trace[1022652342] 'range keys from in-memory index tree' (duration: 209.737548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:49:48.469249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.957554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-20T10:49:48.469424Z","caller":"traceutil/trace.go:171","msg":"trace[1947696039] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2160; }","duration":"149.2063ms","start":"2026-04-20T10:49:48.320211Z","end":"2026-04-20T10:49:48.469417Z","steps":["trace[1947696039] 'range keys from in-memory index tree' (duration: 148.747309ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:50:02.381338Z","caller":"traceutil/trace.go:171","msg":"trace[160008652] linearizableReadLoop","detail":"{readStateIndex:2317; appliedIndex:2315; }","duration":"113.25637ms","start":"2026-04-20T10:50:02.268056Z","end":"2026-04-20T10:50:02.381312Z","steps":["trace[160008652] 'read index received' (duration: 79.3761ms)","trace[160008652] 'applied index is now lower than readState.Index' (duration: 33.87909ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:50:02.381404Z","caller":"traceutil/trace.go:171","msg":"trace[1899016767] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"173.413426ms","start":"2026-04-20T10:50:02.207961Z","end":"2026-04-20T10:50:02.381375Z","steps":["trace[1899016767] 'process raft request' (duration: 173.219912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:50:02.381466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.394403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:50:02.381504Z","caller":"traceutil/trace.go:171","msg":"trace[1359463764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2207; }","duration":"113.473185ms","start":"2026-04-20T10:50:02.268016Z","end":"2026-04-20T10:50:02.381489Z","steps":["trace[1359463764] 'agreement among raft nodes before linearized reading' (duration: 113.400223ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:50:02.381555Z","caller":"traceutil/trace.go:171","msg":"trace[1879484259] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"271.422324ms","start":"2026-04-20T10:50:02.110098Z","end":"2026-04-20T10:50:02.38152Z","steps":["trace[1879484259] 'process raft request' (duration: 237.36609ms)","trace[1879484259] 'compare' (duration: 33.459249ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:50:06.668394Z","caller":"traceutil/trace.go:171","msg":"trace[819192262] linearizableReadLoop","detail":"{readStateIndex:2371; appliedIndex:2370; }","duration":"115.734732ms","start":"2026-04-20T10:50:06.552632Z","end":"2026-04-20T10:50:06.668367Z","steps":["trace[819192262] 'read index received' (duration: 115.473496ms)","trace[819192262] 'applied index is now lower than readState.Index' (duration: 260.006µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:50:06.668529Z","caller":"traceutil/trace.go:171","msg":"trace[1707426867] transaction","detail":"{read_only:false; response_revision:2261; number_of_response:1; }","duration":"276.439121ms","start":"2026-04-20T10:50:06.392076Z","end":"2026-04-20T10:50:06.668515Z","steps":["trace[1707426867] 'process raft request' (duration: 276.127993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:50:06.668797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.153213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-20T10:50:06.668855Z","caller":"traceutil/trace.go:171","msg":"trace[485320448] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2261; }","duration":"116.260166ms","start":"2026-04-20T10:50:06.552582Z","end":"2026-04-20T10:50:06.668842Z","steps":["trace[485320448] 'agreement among raft nodes before linearized reading' (duration: 116.088162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:50:06.669031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.721507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-20T10:50:06.669118Z","caller":"traceutil/trace.go:171","msg":"trace[2122982155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2261; }","duration":"110.83883ms","start":"2026-04-20T10:50:06.558252Z","end":"2026-04-20T10:50:06.66909Z","steps":["trace[2122982155] 'agreement among raft nodes before linearized reading' (duration: 110.664316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:50:06.669139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.262805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-20T10:50:06.669182Z","caller":"traceutil/trace.go:171","msg":"trace[1603246342] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2261; }","duration":"114.315686ms","start":"2026-04-20T10:50:06.554853Z","end":"2026-04-20T10:50:06.669169Z","steps":["trace[1603246342] 'agreement among raft nodes before linearized reading' (duration: 114.226354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:50:06.669031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.531892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-20T10:50:06.669275Z","caller":"traceutil/trace.go:171","msg":"trace[789366961] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2261; }","duration":"114.815808ms","start":"2026-04-20T10:50:06.554451Z","end":"2026-04-20T10:50:06.669267Z","steps":["trace[789366961] 'agreement among raft nodes before linearized reading' (duration: 114.429929ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:50:06.814508Z","caller":"traceutil/trace.go:171","msg":"trace[1338672916] transaction","detail":"{read_only:false; response_revision:2262; number_of_response:1; }","duration":"141.027515ms","start":"2026-04-20T10:50:06.673458Z","end":"2026-04-20T10:50:06.814485Z","steps":["trace[1338672916] 'process raft request' (duration: 140.881061ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:50:06.821561Z","caller":"traceutil/trace.go:171","msg":"trace[2105774768] transaction","detail":"{read_only:false; response_revision:2263; number_of_response:1; }","duration":"147.318812ms","start":"2026-04-20T10:50:06.674226Z","end":"2026-04-20T10:50:06.821544Z","steps":["trace[2105774768] 'process raft request' (duration: 147.205289ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:50:06.821782Z","caller":"traceutil/trace.go:171","msg":"trace[913079218] transaction","detail":"{read_only:false; response_revision:2264; number_of_response:1; }","duration":"147.427336ms","start":"2026-04-20T10:50:06.674334Z","end":"2026-04-20T10:50:06.821761Z","steps":["trace[913079218] 'process raft request' (duration: 147.177378ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:50:06.822105Z","caller":"traceutil/trace.go:171","msg":"trace[986592972] transaction","detail":"{read_only:false; response_revision:2265; number_of_response:1; }","duration":"147.688722ms","start":"2026-04-20T10:50:06.674398Z","end":"2026-04-20T10:50:06.822087Z","steps":["trace[986592972] 'process raft request' (duration: 147.292421ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:50:06.822095Z","caller":"traceutil/trace.go:171","msg":"trace[1393356642] transaction","detail":"{read_only:false; response_revision:2266; number_of_response:1; }","duration":"147.228331ms","start":"2026-04-20T10:50:06.67484Z","end":"2026-04-20T10:50:06.822069Z","steps":["trace[1393356642] 'process raft request' (duration: 147.131109ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:50:08.873011Z","caller":"traceutil/trace.go:171","msg":"trace[765322592] transaction","detail":"{read_only:false; response_revision:2267; number_of_response:1; }","duration":"198.401885ms","start":"2026-04-20T10:50:08.674551Z","end":"2026-04-20T10:50:08.872953Z","steps":["trace[765322592] 'process raft request' (duration: 197.968544ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:50:09.183332Z","caller":"traceutil/trace.go:171","msg":"trace[1525702741] transaction","detail":"{read_only:false; response_revision:2268; number_of_response:1; }","duration":"304.058261ms","start":"2026-04-20T10:50:08.879241Z","end":"2026-04-20T10:50:09.183299Z","steps":["trace[1525702741] 'process raft request' (duration: 303.877947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:50:09.183513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:50:08.87922Z","time spent":"304.198706ms","remote":"127.0.0.1:56708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:50:09.204192Z","caller":"traceutil/trace.go:171","msg":"trace[775427151] transaction","detail":"{read_only:false; response_revision:2269; number_of_response:1; }","duration":"324.922255ms","start":"2026-04-20T10:50:08.879246Z","end":"2026-04-20T10:50:09.204168Z","steps":["trace[775427151] 'process raft request' (duration: 324.753581ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:50:09.204413Z","caller":"traceutil/trace.go:171","msg":"trace[313554610] transaction","detail":"{read_only:false; response_revision:2270; number_of_response:1; }","duration":"325.077729ms","start":"2026-04-20T10:50:08.879311Z","end":"2026-04-20T10:50:09.204389Z","steps":["trace[313554610] 'process raft request' (duration: 324.813563ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:50:09.204447Z","caller":"traceutil/trace.go:171","msg":"trace[127125907] transaction","detail":"{read_only:false; response_revision:2272; number_of_response:1; }","duration":"324.946936ms","start":"2026-04-20T10:50:08.879471Z","end":"2026-04-20T10:50:09.204418Z","steps":["trace[127125907] 'process raft request' (duration: 324.901435ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:50:09.204509Z","caller":"traceutil/trace.go:171","msg":"trace[2021138818] transaction","detail":"{read_only:false; response_revision:2271; number_of_response:1; }","duration":"325.11379ms","start":"2026-04-20T10:50:08.879364Z","end":"2026-04-20T10:50:09.204478Z","steps":["trace[2021138818] 'process raft request' (duration: 324.923135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:50:09.204532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:50:08.879305Z","time spent":"325.165741ms","remote":"127.0.0.1:56708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:50:09.205609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:50:08.879217Z","time spent":"325.055908ms","remote":"127.0.0.1:56708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:50:09.205724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:50:08.879354Z","time spent":"325.225423ms","remote":"127.0.0.1:56708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:50:09.205676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:50:08.879448Z","time spent":"325.040808ms","remote":"127.0.0.1:56708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:50:09.77203Z","caller":"traceutil/trace.go:171","msg":"trace[191691359] transaction","detail":"{read_only:false; response_revision:2274; number_of_response:1; }","duration":"232.396106ms","start":"2026-04-20T10:50:09.539608Z","end":"2026-04-20T10:50:09.772004Z","steps":["trace[191691359] 'process raft request' (duration: 232.260772ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:50:22.304425Z","caller":"traceutil/trace.go:171","msg":"trace[204640447] transaction","detail":"{read_only:false; response_revision:2375; number_of_response:1; }","duration":"323.289114ms","start":"2026-04-20T10:50:21.981121Z","end":"2026-04-20T10:50:22.30441Z","steps":["trace[204640447] 'process raft request' (duration: 323.205622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:50:22.304522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:50:21.981103Z","time spent":"323.367546ms","remote":"127.0.0.1:56708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:50:22.655265Z","caller":"traceutil/trace.go:171","msg":"trace[913269832] linearizableReadLoop","detail":"{readStateIndex:2489; appliedIndex:2488; }","duration":"493.313207ms","start":"2026-04-20T10:50:22.16193Z","end":"2026-04-20T10:50:22.655243Z","steps":["trace[913269832] 'read index received' (duration: 142.487642ms)","trace[913269832] 'applied index is now lower than readState.Index' (duration: 350.824235ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:50:22.655297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:50:21.981145Z","time spent":"674.14813ms","remote":"127.0.0.1:56474","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-20T10:50:22.655461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.194377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:50:22.655513Z","caller":"traceutil/trace.go:171","msg":"trace[1672501309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2375; }","duration":"389.288688ms","start":"2026-04-20T10:50:22.26621Z","end":"2026-04-20T10:50:22.655499Z","steps":["trace[1672501309] 'agreement among raft nodes before linearized reading' (duration: 389.176856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:50:22.655553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:50:22.266192Z","time spent":"389.352131ms","remote":"127.0.0.1:56432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T10:50:22.655535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.593925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-20T10:50:22.655605Z","caller":"traceutil/trace.go:171","msg":"trace[749357921] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2375; }","duration":"493.727208ms","start":"2026-04-20T10:50:22.16186Z","end":"2026-04-20T10:50:22.655588Z","steps":["trace[749357921] 'agreement among raft nodes before linearized reading' (duration: 493.517262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:50:22.655644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:50:22.161844Z","time spent":"493.791979ms","remote":"127.0.0.1:56708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-04-20T10:50:22.828386Z","caller":"traceutil/trace.go:171","msg":"trace[818294493] linearizableReadLoop","detail":"{readStateIndex:2491; appliedIndex:2490; }","duration":"142.633236ms","start":"2026-04-20T10:50:22.685727Z","end":"2026-04-20T10:50:22.828361Z","steps":["trace[818294493] 'read index received' (duration: 124.489141ms)","trace[818294493] 'applied index is now lower than readState.Index' (duration: 18.142915ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:50:22.828447Z","caller":"traceutil/trace.go:171","msg":"trace[1943831409] transaction","detail":"{read_only:false; response_revision:2377; number_of_response:1; }","duration":"169.074858ms","start":"2026-04-20T10:50:22.659325Z","end":"2026-04-20T10:50:22.828399Z","steps":["trace[1943831409] 'process raft request' (duration: 150.968354ms)","trace[1943831409] 'compare' (duration: 17.94375ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:50:22.828569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.82292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-20T10:50:22.828616Z","caller":"traceutil/trace.go:171","msg":"trace[2039820765] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2377; }","duration":"142.916183ms","start":"2026-04-20T10:50:22.685686Z","end":"2026-04-20T10:50:22.828602Z","steps":["trace[2039820765] 'agreement among raft nodes before linearized reading' (duration: 142.80343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:50:22.828629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.561069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:50:22.828731Z","caller":"traceutil/trace.go:171","msg":"trace[915207748] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2377; }","duration":"137.721323ms","start":"2026-04-20T10:50:22.690986Z","end":"2026-04-20T10:50:22.828707Z","steps":["trace[915207748] 'agreement among raft nodes before linearized reading' (duration: 137.553968ms)"],"step_count":1}