{"level":"warn","ts":"2026-05-15T15:17:46.794341Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-15T15:17:46.794483Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.227: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.227:2380","--initial-cluster=instance=https://199.204.45.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.227: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-05-15T15:17:46.794552Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-15T15:17:46.794566Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-05-15T15:17:46.794585Z","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-05-15T15:17:46.795371Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"]} {"level":"info","ts":"2026-05-15T15:17:46.795453Z","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.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.227: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-05-15T15:17:46.801846Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.119798ms"} {"level":"info","ts":"2026-05-15T15:17:46.809029Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"572db18442eba1c2","cluster-id":"fb1028a037bf3cd6"} {"level":"info","ts":"2026-05-15T15:17:46.809123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=()"} {"level":"info","ts":"2026-05-15T15:17:46.809146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 0"} {"level":"info","ts":"2026-05-15T15:17:46.809159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 572db18442eba1c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-15T15:17:46.809168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 1"} {"level":"info","ts":"2026-05-15T15:17:46.809204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"warn","ts":"2026-05-15T15:17:46.816138Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-15T15:17:46.817542Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-15T15:17:46.818745Z","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-05-15T15:17:46.820768Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"572db18442eba1c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-15T15:17:46.82105Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"572db18442eba1c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-15T15:17:46.82135Z","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-05-15T15:17:46.821488Z","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-05-15T15:17:46.821564Z","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-05-15T15:17:46.823185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"info","ts":"2026-05-15T15:17:46.8234Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","added-peer-id":"572db18442eba1c2","added-peer-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-05-15T15:17:46.825967Z","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-05-15T15:17:46.826125Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-05-15T15:17:46.826272Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"572db18442eba1c2","initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-15T15:17:46.82631Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-15T15:17:46.826266Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-05-15T15:17:47.810342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 is starting a new election at term 1"} {"level":"info","ts":"2026-05-15T15:17:47.810406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-15T15:17:47.810444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgPreVoteResp from 572db18442eba1c2 at term 1"} {"level":"info","ts":"2026-05-15T15:17:47.81046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became candidate at term 2"} {"level":"info","ts":"2026-05-15T15:17:47.810477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgVoteResp from 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-05-15T15:17:47.810489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became leader at term 2"} {"level":"info","ts":"2026-05-15T15:17:47.810504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 572db18442eba1c2 elected leader 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-05-15T15:17:47.811548Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-15T15:17:47.811546Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"572db18442eba1c2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.227:2379]}","request-path":"/0/members/572db18442eba1c2/attributes","cluster-id":"fb1028a037bf3cd6","publish-timeout":"7s"} {"level":"info","ts":"2026-05-15T15:17:47.811612Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-15T15:17:47.811665Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-15T15:17:47.81196Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-15T15:17:47.811986Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-15T15:17:47.813084Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.227:2379"} {"level":"info","ts":"2026-05-15T15:17:47.813224Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","cluster-version":"3.5"} {"level":"info","ts":"2026-05-15T15:17:47.81332Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-15T15:17:47.813345Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-15T15:17:47.813713Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-15T15:17:52.633108Z","caller":"traceutil/trace.go:171","msg":"trace[704473034] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"193.439941ms","start":"2026-05-15T15:17:52.439648Z","end":"2026-05-15T15:17:52.633088Z","steps":["trace[704473034] 'process raft request' (duration: 190.920734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:17:52.633248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.323927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:17:52.633303Z","caller":"traceutil/trace.go:171","msg":"trace[1341839239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:270; }","duration":"171.443768ms","start":"2026-05-15T15:17:52.46185Z","end":"2026-05-15T15:17:52.633293Z","steps":["trace[1341839239] 'agreement among raft nodes before linearized reading' (duration: 171.278087ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:17:52.633091Z","caller":"traceutil/trace.go:171","msg":"trace[1246590493] linearizableReadLoop","detail":"{readStateIndex:276; appliedIndex:275; }","duration":"171.175326ms","start":"2026-05-15T15:17:52.461891Z","end":"2026-05-15T15:17:52.633066Z","steps":["trace[1246590493] 'read index received' (duration: 168.767059ms)","trace[1246590493] 'applied index is now lower than readState.Index' (duration: 2.407287ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:17:52.776872Z","caller":"traceutil/trace.go:171","msg":"trace[1292160869] transaction","detail":"{read_only:false; number_of_response:0; response_revision:271; }","duration":"269.849706ms","start":"2026-05-15T15:17:52.506991Z","end":"2026-05-15T15:17:52.776841Z","steps":["trace[1292160869] 'process raft request' (duration: 269.799456ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:17:52.7769Z","caller":"traceutil/trace.go:171","msg":"trace[1286071026] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"270.931923ms","start":"2026-05-15T15:17:52.505949Z","end":"2026-05-15T15:17:52.776881Z","steps":["trace[1286071026] 'process raft request' (duration: 270.149628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:17:55.066832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.825001323s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-15T15:17:55.067033Z","caller":"traceutil/trace.go:171","msg":"trace[1298837797] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"2.430320041s","start":"2026-05-15T15:17:52.636682Z","end":"2026-05-15T15:17:55.067002Z","steps":["trace[1298837797] 'process raft request' (duration: 2.43021251s)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:17:55.06707Z","caller":"traceutil/trace.go:171","msg":"trace[1546850178] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"2.28576704s","start":"2026-05-15T15:17:52.781293Z","end":"2026-05-15T15:17:55.06706Z","steps":["trace[1546850178] 'process raft request' (duration: 2.28574353s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:17:55.067105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:17:52.781276Z","time spent":"2.28581601s","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":638,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-15T15:17:55.06721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:17:52.63666Z","time spent":"2.430427791s","remote":"127.0.0.1:55458","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-05-15T15:17:55.067231Z","caller":"traceutil/trace.go:171","msg":"trace[1066890950] transaction","detail":"{read_only:false; number_of_response:0; response_revision:273; }","duration":"2.342915779s","start":"2026-05-15T15:17:52.72431Z","end":"2026-05-15T15:17:55.067226Z","steps":["trace[1066890950] 'process raft request' (duration: 2.342630317s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:17:55.067254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:17:52.724287Z","time spent":"2.342955359s","remote":"127.0.0.1:55364","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T15:17:55.067378Z","caller":"traceutil/trace.go:171","msg":"trace[668749952] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"2.342905999s","start":"2026-05-15T15:17:52.724465Z","end":"2026-05-15T15:17:55.067371Z","steps":["trace[668749952] 'process raft request' (duration: 2.342494246s)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:17:55.067398Z","caller":"traceutil/trace.go:171","msg":"trace[43786133] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"2.432525425s","start":"2026-05-15T15:17:52.63486Z","end":"2026-05-15T15:17:55.067385Z","steps":["trace[43786133] 'process raft request' (duration: 606.688427ms)","trace[43786133] 'compare' (duration: 1.824418909s)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:17:55.067428Z","caller":"traceutil/trace.go:171","msg":"trace[1429141085] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"2.342486867s","start":"2026-05-15T15:17:52.724936Z","end":"2026-05-15T15:17:55.067423Z","steps":["trace[1429141085] 'process raft request' (duration: 2.342049294s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:17:55.067451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:17:52.634826Z","time spent":"2.432601687s","remote":"127.0.0.1:55366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1196,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-15T15:17:55.067458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:17:52.724923Z","time spent":"2.342520427s","remote":"127.0.0.1:55364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T15:17:55.067551Z","caller":"traceutil/trace.go:171","msg":"trace[204229848] transaction","detail":"{read_only:false; number_of_response:0; response_revision:275; }","duration":"2.342431145s","start":"2026-05-15T15:17:52.725114Z","end":"2026-05-15T15:17:55.067545Z","steps":["trace[204229848] 'process raft request' (duration: 2.341888352s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:17:55.067579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:17:52.725069Z","time spent":"2.342496107s","remote":"127.0.0.1:55364","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-15T15:17:55.067408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:17:52.724455Z","time spent":"2.342939439s","remote":"127.0.0.1:55364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T15:17:55.067668Z","caller":"traceutil/trace.go:171","msg":"trace[786017073] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"2.342291365s","start":"2026-05-15T15:17:52.725368Z","end":"2026-05-15T15:17:55.06766Z","steps":["trace[786017073] 'process raft request' (duration: 2.341645431s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:17:55.067702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:17:52.725344Z","time spent":"2.342341945s","remote":"127.0.0.1:55364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T15:17:55.067794Z","caller":"traceutil/trace.go:171","msg":"trace[429734899] linearizableReadLoop","detail":"{readStateIndex:280; appliedIndex:276; }","duration":"2.348394017s","start":"2026-05-15T15:17:52.719391Z","end":"2026-05-15T15:17:55.067785Z","steps":["trace[429734899] 'read index received' (duration: 56.724946ms)","trace[429734899] 'applied index is now lower than readState.Index' (duration: 2.291668311s)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:17:55.068079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.70935327s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:17:55.068166Z","caller":"traceutil/trace.go:171","msg":"trace[933327612] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:277; }","duration":"1.70949552s","start":"2026-05-15T15:17:53.35865Z","end":"2026-05-15T15:17:55.068146Z","steps":["trace[933327612] 'agreement among raft nodes before linearized reading' (duration: 1.70935156s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:17:55.068202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:17:53.358626Z","time spent":"1.709568231s","remote":"127.0.0.1:55458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-15T15:17:55.068216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.289845038s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"warn","ts":"2026-05-15T15:17:55.068284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.853362398s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-15T15:17:55.068312Z","caller":"traceutil/trace.go:171","msg":"trace[36102532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:277; }","duration":"1.853423189s","start":"2026-05-15T15:17:53.21488Z","end":"2026-05-15T15:17:55.068304Z","steps":["trace[36102532] 'agreement among raft nodes before linearized reading' (duration: 1.853365198s)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:17:55.068314Z","caller":"traceutil/trace.go:171","msg":"trace[2011902468] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:277; }","duration":"2.289997259s","start":"2026-05-15T15:17:52.778284Z","end":"2026-05-15T15:17:55.068281Z","steps":["trace[2011902468] 'agreement among raft nodes before linearized reading' (duration: 2.289693127s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:17:55.068334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:17:53.214861Z","time spent":"1.85346666s","remote":"127.0.0.1:55458","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-05-15T15:17:55.068374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:17:52.778216Z","time spent":"2.29014364s","remote":"127.0.0.1:55352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4040,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-15T15:17:55.068467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.349039271s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:17:55.068597Z","caller":"traceutil/trace.go:171","msg":"trace[969601958] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:277; }","duration":"2.349225943s","start":"2026-05-15T15:17:52.719337Z","end":"2026-05-15T15:17:55.068563Z","steps":["trace[969601958] 'agreement among raft nodes before linearized reading' (duration: 2.348997762s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:17:55.068656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:17:52.719317Z","time spent":"2.349329344s","remote":"127.0.0.1:55458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-15T15:17:55.573728Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052698327858018,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-15T15:17:55.927015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.069514ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-15T15:17:55.927114Z","caller":"traceutil/trace.go:171","msg":"trace[958420975] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"854.264752ms","start":"2026-05-15T15:17:55.072832Z","end":"2026-05-15T15:17:55.927096Z","steps":["trace[958420975] 'process raft request' (duration: 605.053267ms)","trace[958420975] 'compare' (duration: 248.930803ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:17:55.927166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:17:55.072821Z","time spent":"854.325892ms","remote":"127.0.0.1:55458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T15:17:55.928544Z","caller":"traceutil/trace.go:171","msg":"trace[60044478] linearizableReadLoop","detail":"{readStateIndex:291; appliedIndex:286; }","duration":"855.45715ms","start":"2026-05-15T15:17:55.073064Z","end":"2026-05-15T15:17:55.928521Z","steps":["trace[60044478] 'read index received' (duration: 604.832387ms)","trace[60044478] 'applied index is now lower than readState.Index' (duration: 250.623863ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:17:55.928717Z","caller":"traceutil/trace.go:171","msg":"trace[449078408] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"853.070273ms","start":"2026-05-15T15:17:55.075601Z","end":"2026-05-15T15:17:55.928671Z","steps":["trace[449078408] 'process raft request' (duration: 852.800892ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:17:55.928812Z","caller":"traceutil/trace.go:171","msg":"trace[30331146] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"852.945993ms","start":"2026-05-15T15:17:55.075852Z","end":"2026-05-15T15:17:55.928797Z","steps":["trace[30331146] 'process raft request' (duration: 852.614451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:17:55.928865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:17:55.07559Z","time spent":"853.208564ms","remote":"127.0.0.1:55288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1934,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T15:17:55.928598Z","caller":"traceutil/trace.go:171","msg":"trace[376695516] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"853.99242ms","start":"2026-05-15T15:17:55.074588Z","end":"2026-05-15T15:17:55.92858Z","steps":["trace[376695516] 'process raft request' (duration: 853.766138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:17:55.929054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:17:55.075841Z","time spent":"853.007464ms","remote":"127.0.0.1:55458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:17:55.929177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:17:55.074577Z","time spent":"854.528323ms","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":636,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-15T15:17:55.929219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.044854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3980"} {"level":"warn","ts":"2026-05-15T15:17:55.929224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.527357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:17:55.929269Z","caller":"traceutil/trace.go:171","msg":"trace[777774277] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:282; }","duration":"853.584247ms","start":"2026-05-15T15:17:55.075672Z","end":"2026-05-15T15:17:55.929256Z","steps":["trace[777774277] 'agreement among raft nodes before linearized reading' (duration: 853.510797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:17:55.929306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:17:55.075655Z","time spent":"853.644318ms","remote":"127.0.0.1:55200","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-05-15T15:17:55.929288Z","caller":"traceutil/trace.go:171","msg":"trace[1293463456] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:282; }","duration":"856.137364ms","start":"2026-05-15T15:17:55.073116Z","end":"2026-05-15T15:17:55.929253Z","steps":["trace[1293463456] 'agreement among raft nodes before linearized reading' (duration: 855.989933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:17:55.929341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.675168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-15T15:17:55.929379Z","caller":"traceutil/trace.go:171","msg":"trace[407965414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:282; }","duration":"853.737848ms","start":"2026-05-15T15:17:55.075629Z","end":"2026-05-15T15:17:55.929367Z","steps":["trace[407965414] 'agreement among raft nodes before linearized reading' (duration: 853.667288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:17:55.929407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:17:55.073108Z","time spent":"856.287005ms","remote":"127.0.0.1:55364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4003,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-05-15T15:17:55.92942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:17:55.07561Z","time spent":"853.802618ms","remote":"127.0.0.1:55458","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-05-15T15:17:55.929435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.181535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-05-15T15:17:55.930473Z","caller":"traceutil/trace.go:171","msg":"trace[1416655592] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:282; }","duration":"857.434183ms","start":"2026-05-15T15:17:55.073021Z","end":"2026-05-15T15:17:55.930455Z","steps":["trace[1416655592] 'agreement among raft nodes before linearized reading' (duration: 855.827633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:17:55.930515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:17:55.073003Z","time spent":"857.497214ms","remote":"127.0.0.1:55352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4040,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-05-15T15:17:55.931129Z","caller":"traceutil/trace.go:171","msg":"trace[1932702293] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"855.514961ms","start":"2026-05-15T15:17:55.073241Z","end":"2026-05-15T15:17:55.928756Z","steps":["trace[1932702293] 'process raft request' (duration: 854.921667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:17:55.931199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:17:55.073227Z","time spent":"857.941227ms","remote":"127.0.0.1:55458","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-05-15T15:17:57.14199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.316804ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-15T15:17:57.142099Z","caller":"traceutil/trace.go:171","msg":"trace[1817333506] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"160.150672ms","start":"2026-05-15T15:17:56.981929Z","end":"2026-05-15T15:17:57.142079Z","steps":["trace[1817333506] 'process raft request' (duration: 49.672367ms)","trace[1817333506] 'compare' (duration: 110.181193ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:18:00.55662Z","caller":"traceutil/trace.go:171","msg":"trace[1463843435] linearizableReadLoop","detail":"{readStateIndex:352; appliedIndex:351; }","duration":"125.016648ms","start":"2026-05-15T15:18:00.431539Z","end":"2026-05-15T15:18:00.556555Z","steps":["trace[1463843435] 'read index received' (duration: 124.749906ms)","trace[1463843435] 'applied index is now lower than readState.Index' (duration: 265.632µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:18:00.557194Z","caller":"traceutil/trace.go:171","msg":"trace[423372540] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"135.861439ms","start":"2026-05-15T15:18:00.421244Z","end":"2026-05-15T15:18:00.557105Z","steps":["trace[423372540] 'process raft request' (duration: 135.137425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:00.557244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.649312ms","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":"info","ts":"2026-05-15T15:18:00.557355Z","caller":"traceutil/trace.go:171","msg":"trace[1587521789] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:343; }","duration":"125.843893ms","start":"2026-05-15T15:18:00.43149Z","end":"2026-05-15T15:18:00.557334Z","steps":["trace[1587521789] 'agreement among raft nodes before linearized reading' (duration: 125.561041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:00.864759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.12121ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-15T15:18:00.864938Z","caller":"traceutil/trace.go:171","msg":"trace[493903589] linearizableReadLoop","detail":"{readStateIndex:353; appliedIndex:352; }","duration":"282.400349ms","start":"2026-05-15T15:18:00.582472Z","end":"2026-05-15T15:18:00.864872Z","steps":["trace[493903589] 'read index received' (duration: 78.083957ms)","trace[493903589] 'applied index is now lower than readState.Index' (duration: 204.314632ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:18:00.865689Z","caller":"traceutil/trace.go:171","msg":"trace[1076405457] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"300.405748ms","start":"2026-05-15T15:18:00.565252Z","end":"2026-05-15T15:18:00.865658Z","steps":["trace[1076405457] 'process raft request' (duration: 95.310161ms)","trace[1076405457] 'compare' (duration: 203.845808ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:18:00.865782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:00.56523Z","time spent":"300.499278ms","remote":"127.0.0.1:55364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5149,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:18:00.866058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.589556ms","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-05-15T15:18:00.86612Z","caller":"traceutil/trace.go:171","msg":"trace[848766428] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:344; }","duration":"283.696507ms","start":"2026-05-15T15:18:00.582408Z","end":"2026-05-15T15:18:00.866105Z","steps":["trace[848766428] 'agreement among raft nodes before linearized reading' (duration: 283.601236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:00.866378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.646845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-15T15:18:00.866452Z","caller":"traceutil/trace.go:171","msg":"trace[2147327686] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:344; }","duration":"233.731136ms","start":"2026-05-15T15:18:00.632708Z","end":"2026-05-15T15:18:00.866439Z","steps":["trace[2147327686] 'agreement among raft nodes before linearized reading' (duration: 233.619465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:00.867186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.837793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"warn","ts":"2026-05-15T15:18:00.867229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.797101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-15T15:18:00.867281Z","caller":"traceutil/trace.go:171","msg":"trace[631356670] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:344; }","duration":"149.866591ms","start":"2026-05-15T15:18:00.717391Z","end":"2026-05-15T15:18:00.867258Z","steps":["trace[631356670] 'agreement among raft nodes before linearized reading' (duration: 149.792191ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:00.867292Z","caller":"traceutil/trace.go:171","msg":"trace[1069501313] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:344; }","duration":"134.973613ms","start":"2026-05-15T15:18:00.73228Z","end":"2026-05-15T15:18:00.867254Z","steps":["trace[1069501313] 'agreement among raft nodes before linearized reading' (duration: 134.787422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:00.869039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.404393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-15T15:18:00.870661Z","caller":"traceutil/trace.go:171","msg":"trace[883679022] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:344; }","duration":"186.538143ms","start":"2026-05-15T15:18:00.682564Z","end":"2026-05-15T15:18:00.869103Z","steps":["trace[883679022] 'agreement among raft nodes before linearized reading' (duration: 184.880053ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:01.071724Z","caller":"traceutil/trace.go:171","msg":"trace[929673715] linearizableReadLoop","detail":"{readStateIndex:365; appliedIndex:361; }","duration":"139.885503ms","start":"2026-05-15T15:18:00.931815Z","end":"2026-05-15T15:18:01.071701Z","steps":["trace[929673715] 'read index received' (duration: 90.202767ms)","trace[929673715] 'applied index is now lower than readState.Index' (duration: 49.681726ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:18:01.071747Z","caller":"traceutil/trace.go:171","msg":"trace[1283066593] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"171.739213ms","start":"2026-05-15T15:18:00.899949Z","end":"2026-05-15T15:18:01.071688Z","steps":["trace[1283066593] 'process raft request' (duration: 171.579472ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:01.071748Z","caller":"traceutil/trace.go:171","msg":"trace[1648053427] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"172.80373ms","start":"2026-05-15T15:18:00.898912Z","end":"2026-05-15T15:18:01.071716Z","steps":["trace[1648053427] 'process raft request' (duration: 123.108444ms)","trace[1648053427] 'compare' (duration: 49.315624ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:18:01.072419Z","caller":"traceutil/trace.go:171","msg":"trace[819204434] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"151.428499ms","start":"2026-05-15T15:18:00.920969Z","end":"2026-05-15T15:18:01.072397Z","steps":["trace[819204434] 'process raft request' (duration: 150.681414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:01.072722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.88938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-05-15T15:18:01.072783Z","caller":"traceutil/trace.go:171","msg":"trace[1356711002] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:355; }","duration":"140.97743ms","start":"2026-05-15T15:18:00.93179Z","end":"2026-05-15T15:18:01.072767Z","steps":["trace[1356711002] 'agreement among raft nodes before linearized reading' (duration: 140.86558ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:01.072432Z","caller":"traceutil/trace.go:171","msg":"trace[222351942] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"171.19279ms","start":"2026-05-15T15:18:00.901032Z","end":"2026-05-15T15:18:01.072224Z","steps":["trace[222351942] 'process raft request' (duration: 170.550696ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:01.32191Z","caller":"traceutil/trace.go:171","msg":"trace[683866769] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"162.66811ms","start":"2026-05-15T15:18:01.159219Z","end":"2026-05-15T15:18:01.321888Z","steps":["trace[683866769] 'process raft request' (duration: 98.454088ms)","trace[683866769] 'compare' (duration: 64.078331ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:18:01.32479Z","caller":"traceutil/trace.go:171","msg":"trace[1891999214] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"162.944972ms","start":"2026-05-15T15:18:01.161798Z","end":"2026-05-15T15:18:01.324743Z","steps":["trace[1891999214] 'process raft request' (duration: 162.75533ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:01.324867Z","caller":"traceutil/trace.go:171","msg":"trace[1306025057] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"160.846168ms","start":"2026-05-15T15:18:01.163998Z","end":"2026-05-15T15:18:01.324844Z","steps":["trace[1306025057] 'process raft request' (duration: 160.643997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:01.325086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.184962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-15T15:18:01.325167Z","caller":"traceutil/trace.go:171","msg":"trace[15081070] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:371; }","duration":"143.312522ms","start":"2026-05-15T15:18:01.181843Z","end":"2026-05-15T15:18:01.325156Z","steps":["trace[15081070] 'agreement among raft nodes before linearized reading' (duration: 143.140681ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:01.324836Z","caller":"traceutil/trace.go:171","msg":"trace[876716882] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:378; }","duration":"142.9273ms","start":"2026-05-15T15:18:01.18189Z","end":"2026-05-15T15:18:01.324817Z","steps":["trace[876716882] 'read index received' (duration: 75.878059ms)","trace[876716882] 'applied index is now lower than readState.Index' (duration: 67.047941ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:18:01.511742Z","caller":"traceutil/trace.go:171","msg":"trace[941674206] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"118.524119ms","start":"2026-05-15T15:18:01.393192Z","end":"2026-05-15T15:18:01.511716Z","steps":["trace[941674206] 'process raft request' (duration: 116.391375ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:01.585918Z","caller":"traceutil/trace.go:171","msg":"trace[1067569896] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"192.137472ms","start":"2026-05-15T15:18:01.393761Z","end":"2026-05-15T15:18:01.585898Z","steps":["trace[1067569896] 'process raft request' (duration: 192.090832ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:01.585931Z","caller":"traceutil/trace.go:171","msg":"trace[1341067872] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"192.585955ms","start":"2026-05-15T15:18:01.393331Z","end":"2026-05-15T15:18:01.585917Z","steps":["trace[1341067872] 'process raft request' (duration: 192.413134ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:01.586309Z","caller":"traceutil/trace.go:171","msg":"trace[1097561946] linearizableReadLoop","detail":"{readStateIndex:391; appliedIndex:384; }","duration":"134.696845ms","start":"2026-05-15T15:18:01.451603Z","end":"2026-05-15T15:18:01.5863Z","steps":["trace[1097561946] 'read index received' (duration: 57.981911ms)","trace[1097561946] 'applied index is now lower than readState.Index' (duration: 76.714314ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:18:01.586322Z","caller":"traceutil/trace.go:171","msg":"trace[1567031209] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"192.242983ms","start":"2026-05-15T15:18:01.394071Z","end":"2026-05-15T15:18:01.586315Z","steps":["trace[1567031209] 'process raft request' (duration: 191.80666ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:01.586339Z","caller":"traceutil/trace.go:171","msg":"trace[19528449] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"192.185993ms","start":"2026-05-15T15:18:01.394115Z","end":"2026-05-15T15:18:01.586301Z","steps":["trace[19528449] 'process raft request' (duration: 192.019562ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:01.586355Z","caller":"traceutil/trace.go:171","msg":"trace[1870268306] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"191.503808ms","start":"2026-05-15T15:18:01.394847Z","end":"2026-05-15T15:18:01.586351Z","steps":["trace[1870268306] 'process raft request' (duration: 191.383807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:01.586536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.936766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-05-15T15:18:01.586561Z","caller":"traceutil/trace.go:171","msg":"trace[1046096869] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:381; }","duration":"135.019578ms","start":"2026-05-15T15:18:01.451536Z","end":"2026-05-15T15:18:01.586556Z","steps":["trace[1046096869] 'agreement among raft nodes before linearized reading' (duration: 134.965907ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:01.586632Z","caller":"traceutil/trace.go:171","msg":"trace[2138566775] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"184.365382ms","start":"2026-05-15T15:18:01.402262Z","end":"2026-05-15T15:18:01.586628Z","steps":["trace[2138566775] 'process raft request' (duration: 184.00731ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:01.666874Z","caller":"traceutil/trace.go:171","msg":"trace[1807773474] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"148.249493ms","start":"2026-05-15T15:18:01.518597Z","end":"2026-05-15T15:18:01.666846Z","steps":["trace[1807773474] 'process raft request' (duration: 145.180223ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:01.727694Z","caller":"traceutil/trace.go:171","msg":"trace[798748494] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"134.884318ms","start":"2026-05-15T15:18:01.592792Z","end":"2026-05-15T15:18:01.727676Z","steps":["trace[798748494] 'process raft request' (duration: 134.726737ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:01.727755Z","caller":"traceutil/trace.go:171","msg":"trace[1405240901] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"133.219336ms","start":"2026-05-15T15:18:01.594511Z","end":"2026-05-15T15:18:01.72773Z","steps":["trace[1405240901] 'process raft request' (duration: 133.066815ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:01.727943Z","caller":"traceutil/trace.go:171","msg":"trace[1162379213] linearizableReadLoop","detail":"{readStateIndex:394; appliedIndex:391; }","duration":"133.688049ms","start":"2026-05-15T15:18:01.594241Z","end":"2026-05-15T15:18:01.727929Z","steps":["trace[1162379213] 'read index received' (duration: 69.486717ms)","trace[1162379213] 'applied index is now lower than readState.Index' (duration: 64.200142ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:18:01.728095Z","caller":"traceutil/trace.go:171","msg":"trace[488110674] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"132.748693ms","start":"2026-05-15T15:18:01.595335Z","end":"2026-05-15T15:18:01.728084Z","steps":["trace[488110674] 'process raft request' (duration: 132.353751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:01.728119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.85397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-05-15T15:18:01.728185Z","caller":"traceutil/trace.go:171","msg":"trace[1553448544] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:386; }","duration":"133.952921ms","start":"2026-05-15T15:18:01.594212Z","end":"2026-05-15T15:18:01.728165Z","steps":["trace[1553448544] 'agreement among raft nodes before linearized reading' (duration: 133.7706ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:01.728194Z","caller":"traceutil/trace.go:171","msg":"trace[80028655] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"134.841557ms","start":"2026-05-15T15:18:01.592785Z","end":"2026-05-15T15:18:01.727626Z","steps":["trace[80028655] 'process raft request' (duration: 134.609856ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:01.851232Z","caller":"traceutil/trace.go:171","msg":"trace[84463227] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"118.489599ms","start":"2026-05-15T15:18:01.732719Z","end":"2026-05-15T15:18:01.851209Z","steps":["trace[84463227] 'process raft request' (duration: 118.425299ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:01.851478Z","caller":"traceutil/trace.go:171","msg":"trace[498183418] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"177.183866ms","start":"2026-05-15T15:18:01.674267Z","end":"2026-05-15T15:18:01.851451Z","steps":["trace[498183418] 'process raft request' (duration: 164.299941ms)","trace[498183418] 'compare' (duration: 12.359201ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:18:01.851663Z","caller":"traceutil/trace.go:171","msg":"trace[967636492] linearizableReadLoop","detail":"{readStateIndex:397; appliedIndex:396; }","duration":"120.030339ms","start":"2026-05-15T15:18:01.731536Z","end":"2026-05-15T15:18:01.851566Z","steps":["trace[967636492] 'read index received' (duration: 107.006903ms)","trace[967636492] 'applied index is now lower than readState.Index' (duration: 13.020726ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:18:01.851721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.16168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-05-15T15:18:01.851796Z","caller":"traceutil/trace.go:171","msg":"trace[1297138410] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:388; }","duration":"120.278811ms","start":"2026-05-15T15:18:01.7315Z","end":"2026-05-15T15:18:01.851779Z","steps":["trace[1297138410] 'agreement among raft nodes before linearized reading' (duration: 120.12121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:01.851849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.237411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-jpdfb\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-05-15T15:18:01.852104Z","caller":"traceutil/trace.go:171","msg":"trace[180532610] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-jpdfb; range_end:; response_count:1; response_revision:388; }","duration":"120.322841ms","start":"2026-05-15T15:18:01.731562Z","end":"2026-05-15T15:18:01.851884Z","steps":["trace[180532610] 'agreement among raft nodes before linearized reading' (duration: 120.233131ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:02.231391Z","caller":"traceutil/trace.go:171","msg":"trace[2066196750] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"119.415521ms","start":"2026-05-15T15:18:02.111957Z","end":"2026-05-15T15:18:02.231373Z","steps":["trace[2066196750] 'process raft request' (duration: 64.983675ms)","trace[2066196750] 'compare' (duration: 54.275665ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:18:02.231565Z","caller":"traceutil/trace.go:171","msg":"trace[967800447] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"119.403501ms","start":"2026-05-15T15:18:02.112155Z","end":"2026-05-15T15:18:02.231559Z","steps":["trace[967800447] 'process raft request' (duration: 119.187989ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:02.231695Z","caller":"traceutil/trace.go:171","msg":"trace[1688405721] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"119.27651ms","start":"2026-05-15T15:18:02.112411Z","end":"2026-05-15T15:18:02.231687Z","steps":["trace[1688405721] 'process raft request' (duration: 119.122109ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:08.403599Z","caller":"traceutil/trace.go:171","msg":"trace[115898324] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"144.828547ms","start":"2026-05-15T15:18:08.25874Z","end":"2026-05-15T15:18:08.403568Z","steps":["trace[115898324] 'process raft request' (duration: 129.061717ms)","trace[115898324] 'compare' (duration: 15.588089ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:18:08.403906Z","caller":"traceutil/trace.go:171","msg":"trace[1992037527] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"144.095352ms","start":"2026-05-15T15:18:08.259772Z","end":"2026-05-15T15:18:08.403867Z","steps":["trace[1992037527] 'process raft request' (duration: 143.75799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:10.079894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.638398ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-15T15:18:10.080104Z","caller":"traceutil/trace.go:171","msg":"trace[409821553] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"478.549971ms","start":"2026-05-15T15:18:09.601492Z","end":"2026-05-15T15:18:10.080042Z","steps":["trace[409821553] 'process raft request' (duration: 233.704922ms)","trace[409821553] 'compare' (duration: 244.174405ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:18:10.080387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:09.601475Z","time spent":"478.744112ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3678,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-15T15:18:10.486199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.699736ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-15T15:18:10.486465Z","caller":"traceutil/trace.go:171","msg":"trace[623660026] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"402.322572ms","start":"2026-05-15T15:18:10.084113Z","end":"2026-05-15T15:18:10.486436Z","steps":["trace[623660026] 'process raft request' (duration: 190.258663ms)","trace[623660026] 'compare' (duration: 211.474795ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:18:10.486536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:10.084087Z","time spent":"402.416512ms","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T15:18:10.494861Z","caller":"traceutil/trace.go:171","msg":"trace[1418955644] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:433; }","duration":"320.79921ms","start":"2026-05-15T15:18:10.17404Z","end":"2026-05-15T15:18:10.494839Z","steps":["trace[1418955644] 'read index received' (duration: 100.381539ms)","trace[1418955644] 'applied index is now lower than readState.Index' (duration: 220.416951ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:18:10.494948Z","caller":"traceutil/trace.go:171","msg":"trace[827885874] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"408.727351ms","start":"2026-05-15T15:18:10.086214Z","end":"2026-05-15T15:18:10.494942Z","steps":["trace[827885874] 'process raft request' (duration: 408.491679ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:10.495013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:10.086191Z","time spent":"408.774662ms","remote":"127.0.0.1:55364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3563,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-15T15:18:10.49504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.999881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-15T15:18:10.495089Z","caller":"traceutil/trace.go:171","msg":"trace[1293655883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:424; }","duration":"321.052412ms","start":"2026-05-15T15:18:10.174023Z","end":"2026-05-15T15:18:10.495075Z","steps":["trace[1293655883] 'agreement among raft nodes before linearized reading' (duration: 320.937161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:10.495132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:10.17395Z","time spent":"321.171162ms","remote":"127.0.0.1:55458","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-05-15T15:18:10.495158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.008757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-15T15:18:10.495178Z","caller":"traceutil/trace.go:171","msg":"trace[1293608885] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:424; }","duration":"223.077438ms","start":"2026-05-15T15:18:10.272094Z","end":"2026-05-15T15:18:10.495171Z","steps":["trace[1293608885] 'agreement among raft nodes before linearized reading' (duration: 223.039868ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:10.49544Z","caller":"traceutil/trace.go:171","msg":"trace[692498853] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"409.109984ms","start":"2026-05-15T15:18:10.086272Z","end":"2026-05-15T15:18:10.495382Z","steps":["trace[692498853] 'process raft request' (duration: 408.52255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:10.495562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:10.086239Z","time spent":"409.242894ms","remote":"127.0.0.1:55664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:18:22.693395Z","caller":"traceutil/trace.go:171","msg":"trace[260456908] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"101.755371ms","start":"2026-05-15T15:18:22.591613Z","end":"2026-05-15T15:18:22.693368Z","steps":["trace[260456908] 'process raft request' (duration: 101.500949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:23.670037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.401405ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-15T15:18:23.670148Z","caller":"traceutil/trace.go:171","msg":"trace[1928450938] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:531; }","duration":"976.695665ms","start":"2026-05-15T15:18:22.693436Z","end":"2026-05-15T15:18:23.670131Z","steps":["trace[1928450938] 'read index received' (duration: 493.201715ms)","trace[1928450938] 'applied index is now lower than readState.Index' (duration: 483.49274ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:18:23.670247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.076104244s","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-05-15T15:18:23.670281Z","caller":"traceutil/trace.go:171","msg":"trace[1562802444] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:513; }","duration":"1.076168467s","start":"2026-05-15T15:18:22.594101Z","end":"2026-05-15T15:18:23.670269Z","steps":["trace[1562802444] 'agreement among raft nodes before linearized reading' (duration: 1.076096414s)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:23.67023Z","caller":"traceutil/trace.go:171","msg":"trace[2145656476] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"1.076143426s","start":"2026-05-15T15:18:22.594035Z","end":"2026-05-15T15:18:23.670178Z","steps":["trace[2145656476] 'process raft request' (duration: 592.52441ms)","trace[2145656476] 'compare' (duration: 483.292911ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:18:23.670327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:22.594084Z","time spent":"1.07623533s","remote":"127.0.0.1:55458","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-05-15T15:18:23.670567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:22.594023Z","time spent":"1.076356335s","remote":"127.0.0.1:55458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:18:23.978575Z","caller":"traceutil/trace.go:171","msg":"trace[409714861] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"1.254345205s","start":"2026-05-15T15:18:22.724208Z","end":"2026-05-15T15:18:23.978553Z","steps":["trace[409714861] 'process raft request' (duration: 1.25425343s)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:23.978593Z","caller":"traceutil/trace.go:171","msg":"trace[1256064619] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.280999251s","start":"2026-05-15T15:18:22.697537Z","end":"2026-05-15T15:18:23.978536Z","steps":["trace[1256064619] 'process raft request' (duration: 1.280730728s)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:23.978789Z","caller":"traceutil/trace.go:171","msg":"trace[2124163355] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"1.240681236s","start":"2026-05-15T15:18:22.738092Z","end":"2026-05-15T15:18:23.978773Z","steps":["trace[2124163355] 'process raft request' (duration: 1.240579951s)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:23.978871Z","caller":"traceutil/trace.go:171","msg":"trace[1878888914] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"1.233080835s","start":"2026-05-15T15:18:22.745783Z","end":"2026-05-15T15:18:23.978864Z","steps":["trace[1878888914] 'process raft request' (duration: 1.232993061s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:23.97889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:22.738072Z","time spent":"1.24076436s","remote":"127.0.0.1:55240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-15T15:18:23.978977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:22.697512Z","time spent":"1.281282604s","remote":"127.0.0.1:55458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:18:23.97878Z","caller":"traceutil/trace.go:171","msg":"trace[456309931] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"1.251503279s","start":"2026-05-15T15:18:22.727239Z","end":"2026-05-15T15:18:23.978742Z","steps":["trace[456309931] 'process raft request' (duration: 1.251273009s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:23.979089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:22.727221Z","time spent":"1.251849026s","remote":"127.0.0.1:55240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T15:18:23.979094Z","caller":"traceutil/trace.go:171","msg":"trace[2074514223] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"1.233383709s","start":"2026-05-15T15:18:22.745698Z","end":"2026-05-15T15:18:23.979082Z","steps":["trace[2074514223] 'process raft request' (duration: 1.233046244s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:23.979167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:22.745675Z","time spent":"1.233455103s","remote":"127.0.0.1:55240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-15T15:18:23.978725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:22.72419Z","time spent":"1.25446015s","remote":"127.0.0.1:55240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T15:18:23.979284Z","caller":"traceutil/trace.go:171","msg":"trace[1003472791] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"1.228821773s","start":"2026-05-15T15:18:22.750452Z","end":"2026-05-15T15:18:23.979274Z","steps":["trace[1003472791] 'process raft request' (duration: 1.228409864s)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:23.97933Z","caller":"traceutil/trace.go:171","msg":"trace[1819453538] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"303.678409ms","start":"2026-05-15T15:18:23.675641Z","end":"2026-05-15T15:18:23.979319Z","steps":["trace[1819453538] 'process raft request' (duration: 303.653387ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:23.97937Z","caller":"traceutil/trace.go:171","msg":"trace[2130664856] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"1.229521446s","start":"2026-05-15T15:18:22.749839Z","end":"2026-05-15T15:18:23.97936Z","steps":["trace[2130664856] 'process raft request' (duration: 1.228969131s)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:23.979389Z","caller":"traceutil/trace.go:171","msg":"trace[719806549] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"1.229452733s","start":"2026-05-15T15:18:22.749928Z","end":"2026-05-15T15:18:23.97938Z","steps":["trace[719806549] 'process raft request' (duration: 1.228904018s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:23.979395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:23.675618Z","time spent":"303.745152ms","remote":"127.0.0.1:55458","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-05-15T15:18:23.979419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:22.749826Z","time spent":"1.229573238s","remote":"127.0.0.1:55240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-15T15:18:23.979429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:22.749923Z","time spent":"1.229487085s","remote":"127.0.0.1:55240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T15:18:23.979458Z","caller":"traceutil/trace.go:171","msg":"trace[1139437063] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"1.208484538s","start":"2026-05-15T15:18:22.770966Z","end":"2026-05-15T15:18:23.97945Z","steps":["trace[1139437063] 'process raft request' (duration: 1.207996345s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:23.979495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:22.770947Z","time spent":"1.20853201s","remote":"127.0.0.1:55240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-15T15:18:23.979495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:22.745776Z","time spent":"1.233116867s","remote":"127.0.0.1:55240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T15:18:23.979541Z","caller":"traceutil/trace.go:171","msg":"trace[2056565408] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"1.208467147s","start":"2026-05-15T15:18:22.771067Z","end":"2026-05-15T15:18:23.979534Z","steps":["trace[2056565408] 'process raft request' (duration: 1.208009875s)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:23.979556Z","caller":"traceutil/trace.go:171","msg":"trace[1764718192] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"1.167949613s","start":"2026-05-15T15:18:22.811601Z","end":"2026-05-15T15:18:23.979551Z","steps":["trace[1764718192] 'process raft request' (duration: 1.167515333s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:23.979572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:22.771039Z","time spent":"1.208518339s","remote":"127.0.0.1:55240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-15T15:18:23.979583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.472315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-15T15:18:23.979608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:22.811579Z","time spent":"1.167997766s","remote":"127.0.0.1:55240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T15:18:23.979525Z","caller":"traceutil/trace.go:171","msg":"trace[1583543438] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:532; }","duration":"309.341274ms","start":"2026-05-15T15:18:23.670173Z","end":"2026-05-15T15:18:23.979514Z","steps":["trace[1583543438] 'read index received' (duration: 308.091615ms)","trace[1583543438] 'applied index is now lower than readState.Index' (duration: 1.248499ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:18:23.979334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:22.750443Z","time spent":"1.228867915s","remote":"127.0.0.1:55240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T15:18:23.979656Z","caller":"traceutil/trace.go:171","msg":"trace[507996011] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"827.842736ms","start":"2026-05-15T15:18:23.151806Z","end":"2026-05-15T15:18:23.979649Z","steps":["trace[507996011] 'process raft request' (duration: 827.445827ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:23.979625Z","caller":"traceutil/trace.go:171","msg":"trace[167837607] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:528; }","duration":"902.536728ms","start":"2026-05-15T15:18:23.077079Z","end":"2026-05-15T15:18:23.979616Z","steps":["trace[167837607] 'agreement among raft nodes before linearized reading' (duration: 902.465195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:23.979715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:23.151781Z","time spent":"827.900239ms","remote":"127.0.0.1:55458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:18:23.979734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:23.077065Z","time spent":"902.660464ms","remote":"127.0.0.1:55200","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-05-15T15:18:23.979661Z","caller":"traceutil/trace.go:171","msg":"trace[1096150421] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"1.038453706s","start":"2026-05-15T15:18:22.941201Z","end":"2026-05-15T15:18:23.979655Z","steps":["trace[1096150421] 'process raft request' (duration: 1.038002345s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:23.979827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:22.941178Z","time spent":"1.038618724s","remote":"127.0.0.1:55240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T15:18:27.032811Z","caller":"traceutil/trace.go:171","msg":"trace[197502367] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"305.453069ms","start":"2026-05-15T15:18:26.727333Z","end":"2026-05-15T15:18:27.032786Z","steps":["trace[197502367] 'read index received' (duration: 305.272971ms)","trace[197502367] 'applied index is now lower than readState.Index' (duration: 178.528µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:18:27.03291Z","caller":"traceutil/trace.go:171","msg":"trace[1506378195] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"308.666434ms","start":"2026-05-15T15:18:26.724196Z","end":"2026-05-15T15:18:27.032862Z","steps":["trace[1506378195] 'process raft request' (duration: 308.442054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:27.033034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.667238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/envoy-gateway-system/\" range_end:\"/registry/resourcequotas/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:18:27.033137Z","caller":"traceutil/trace.go:171","msg":"trace[1994371085] range","detail":"{range_begin:/registry/resourcequotas/envoy-gateway-system/; range_end:/registry/resourcequotas/envoy-gateway-system0; response_count:0; response_revision:596; }","duration":"305.846366ms","start":"2026-05-15T15:18:26.727264Z","end":"2026-05-15T15:18:27.03311Z","steps":["trace[1994371085] 'agreement among raft nodes before linearized reading' (duration: 305.655238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:27.033167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:26.724178Z","time spent":"308.849641ms","remote":"127.0.0.1:55458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:18:27.033199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:26.727237Z","time spent":"305.951481ms","remote":"127.0.0.1:55266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/envoy-gateway-system/\" range_end:\"/registry/resourcequotas/envoy-gateway-system0\" "} {"level":"info","ts":"2026-05-15T15:18:27.195263Z","caller":"traceutil/trace.go:171","msg":"trace[1430098905] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"116.880349ms","start":"2026-05-15T15:18:27.078357Z","end":"2026-05-15T15:18:27.195237Z","steps":["trace[1430098905] 'read index received' (duration: 111.355242ms)","trace[1430098905] 'applied index is now lower than readState.Index' (duration: 5.524317ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:18:27.195314Z","caller":"traceutil/trace.go:171","msg":"trace[2128120094] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"159.290593ms","start":"2026-05-15T15:18:27.036001Z","end":"2026-05-15T15:18:27.195292Z","steps":["trace[2128120094] 'process raft request' (duration: 153.652271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:27.195592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.155471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:18:27.195718Z","caller":"traceutil/trace.go:171","msg":"trace[1775516286] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"117.393571ms","start":"2026-05-15T15:18:27.078292Z","end":"2026-05-15T15:18:27.195686Z","steps":["trace[1775516286] 'agreement among raft nodes before linearized reading' (duration: 117.054026ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:27.40283Z","caller":"traceutil/trace.go:171","msg":"trace[585869591] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"202.190778ms","start":"2026-05-15T15:18:27.200621Z","end":"2026-05-15T15:18:27.402811Z","steps":["trace[585869591] 'process raft request' (duration: 110.305395ms)","trace[585869591] 'compare' (duration: 91.800099ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:18:27.403175Z","caller":"traceutil/trace.go:171","msg":"trace[1761356738] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"202.436658ms","start":"2026-05-15T15:18:27.200704Z","end":"2026-05-15T15:18:27.40314Z","steps":["trace[1761356738] 'process raft request' (duration: 202.2542ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:27.755632Z","caller":"traceutil/trace.go:171","msg":"trace[264556142] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"179.89139ms","start":"2026-05-15T15:18:27.575653Z","end":"2026-05-15T15:18:27.755544Z","steps":["trace[264556142] 'process raft request' (duration: 179.380368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:30.069539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.031834088s","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-05-15T15:18:30.069595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.619752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:18:30.069689Z","caller":"traceutil/trace.go:171","msg":"trace[1608193858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"990.754898ms","start":"2026-05-15T15:18:29.078915Z","end":"2026-05-15T15:18:30.06967Z","steps":["trace[1608193858] 'range keys from in-memory index tree' (duration: 990.532468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:30.069746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:29.078894Z","time spent":"990.841792ms","remote":"127.0.0.1:55198","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-05-15T15:18:30.06963Z","caller":"traceutil/trace.go:171","msg":"trace[1244604606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:607; }","duration":"1.032024836s","start":"2026-05-15T15:18:29.037585Z","end":"2026-05-15T15:18:30.06961Z","steps":["trace[1244604606] 'range keys from in-memory index tree' (duration: 1.031629309s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:30.069942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:29.037565Z","time spent":"1.032365851s","remote":"127.0.0.1:55458","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":"info","ts":"2026-05-15T15:18:30.072755Z","caller":"traceutil/trace.go:171","msg":"trace[1916840871] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"287.126161ms","start":"2026-05-15T15:18:29.785603Z","end":"2026-05-15T15:18:30.072729Z","steps":["trace[1916840871] 'read index received' (duration: 284.610913ms)","trace[1916840871] 'applied index is now lower than readState.Index' (duration: 2.514168ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:18:30.072962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.34252ms","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-05-15T15:18:30.073004Z","caller":"traceutil/trace.go:171","msg":"trace[641880010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:608; }","duration":"287.427724ms","start":"2026-05-15T15:18:29.785565Z","end":"2026-05-15T15:18:30.072993Z","steps":["trace[641880010] 'agreement among raft nodes before linearized reading' (duration: 287.269557ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:30.072918Z","caller":"traceutil/trace.go:171","msg":"trace[1693438559] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"808.133716ms","start":"2026-05-15T15:18:29.264732Z","end":"2026-05-15T15:18:30.072866Z","steps":["trace[1693438559] 'process raft request' (duration: 806.216734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:30.073476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:29.264709Z","time spent":"808.601727ms","remote":"127.0.0.1:55272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036189,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:18:31.947744Z","caller":"traceutil/trace.go:171","msg":"trace[197635644] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"325.612464ms","start":"2026-05-15T15:18:31.6221Z","end":"2026-05-15T15:18:31.947712Z","steps":["trace[197635644] 'process raft request' (duration: 323.812648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:31.948482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:31.622055Z","time spent":"326.292123ms","remote":"127.0.0.1:55272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:18:32.261319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.503196ms","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-05-15T15:18:32.261404Z","caller":"traceutil/trace.go:171","msg":"trace[6251251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:621; }","duration":"164.633982ms","start":"2026-05-15T15:18:32.09675Z","end":"2026-05-15T15:18:32.261384Z","steps":["trace[6251251] 'range keys from in-memory index tree' (duration: 164.305228ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:32.261571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.827784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-15T15:18:32.261743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.797501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-15T15:18:32.261731Z","caller":"traceutil/trace.go:171","msg":"trace[1757289739] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"182.091345ms","start":"2026-05-15T15:18:32.079612Z","end":"2026-05-15T15:18:32.261703Z","steps":["trace[1757289739] 'range keys from in-memory index tree' (duration: 181.698929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:32.26176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.468151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-15T15:18:32.261789Z","caller":"traceutil/trace.go:171","msg":"trace[1511264006] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"175.876874ms","start":"2026-05-15T15:18:32.085898Z","end":"2026-05-15T15:18:32.261775Z","steps":["trace[1511264006] 'range keys from in-memory index tree' (duration: 175.674085ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:32.261817Z","caller":"traceutil/trace.go:171","msg":"trace[474553742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:621; }","duration":"177.528824ms","start":"2026-05-15T15:18:32.084266Z","end":"2026-05-15T15:18:32.261795Z","steps":["trace[474553742] 'range keys from in-memory index tree' (duration: 177.323845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:32.261642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.967618ms","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-05-15T15:18:32.261988Z","caller":"traceutil/trace.go:171","msg":"trace[662382967] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:621; }","duration":"171.330583ms","start":"2026-05-15T15:18:32.090643Z","end":"2026-05-15T15:18:32.261974Z","steps":["trace[662382967] 'range keys from in-memory index tree' (duration: 170.738198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:32.947845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.500059ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-15T15:18:32.947961Z","caller":"traceutil/trace.go:171","msg":"trace[2093932417] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"387.002381ms","start":"2026-05-15T15:18:32.560939Z","end":"2026-05-15T15:18:32.947942Z","steps":["trace[2093932417] 'process raft request' (duration: 245.322825ms)","trace[2093932417] 'compare' (duration: 141.377403ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:18:32.948023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:32.560923Z","time spent":"387.073693ms","remote":"127.0.0.1:55364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:18:33.273174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.20636ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-15T15:18:33.273277Z","caller":"traceutil/trace.go:171","msg":"trace[1234794185] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"704.054636ms","start":"2026-05-15T15:18:32.569205Z","end":"2026-05-15T15:18:33.27326Z","steps":["trace[1234794185] 'process raft request' (duration: 482.697781ms)","trace[1234794185] 'compare' (duration: 221.045022ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:18:33.273331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:32.569197Z","time spent":"704.113589ms","remote":"127.0.0.1:55422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-15T15:18:34.535753Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.198062321s","expected-duration":"1s"} {"level":"info","ts":"2026-05-15T15:18:34.539088Z","caller":"traceutil/trace.go:171","msg":"trace[1036526129] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.2014491s","start":"2026-05-15T15:18:33.337588Z","end":"2026-05-15T15:18:34.539037Z","steps":["trace[1036526129] 'process raft request' (duration: 1.198478318s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:34.539403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:33.337567Z","time spent":"1.201713581s","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T15:18:34.549273Z","caller":"traceutil/trace.go:171","msg":"trace[1897263715] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"1.2102284s","start":"2026-05-15T15:18:33.339013Z","end":"2026-05-15T15:18:34.549241Z","steps":["trace[1897263715] 'process raft request' (duration: 1.209812353s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:34.549424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:33.339Z","time spent":"1.210343254s","remote":"127.0.0.1:55364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:18:34.549447Z","caller":"traceutil/trace.go:171","msg":"trace[2096412246] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"1.210414918s","start":"2026-05-15T15:18:33.339013Z","end":"2026-05-15T15:18:34.549428Z","steps":["trace[2096412246] 'process raft request' (duration: 1.210125826s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:34.549524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:33.338982Z","time spent":"1.210507611s","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T15:18:34.549564Z","caller":"traceutil/trace.go:171","msg":"trace[1484432085] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.210358465s","start":"2026-05-15T15:18:33.339153Z","end":"2026-05-15T15:18:34.549512Z","steps":["trace[1484432085] 'process raft request' (duration: 1.210122936s)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:34.549598Z","caller":"traceutil/trace.go:171","msg":"trace[927625489] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:657; }","duration":"472.314652ms","start":"2026-05-15T15:18:34.077246Z","end":"2026-05-15T15:18:34.549561Z","steps":["trace[927625489] 'read index received' (duration: 458.785317ms)","trace[927625489] 'applied index is now lower than readState.Index' (duration: 13.527875ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:18:34.549729Z","caller":"traceutil/trace.go:171","msg":"trace[614088451] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"211.428742ms","start":"2026-05-15T15:18:34.338287Z","end":"2026-05-15T15:18:34.549716Z","steps":["trace[614088451] 'process raft request' (duration: 211.125839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:34.549757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.506651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:18:34.549798Z","caller":"traceutil/trace.go:171","msg":"trace[240678945] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"472.576074ms","start":"2026-05-15T15:18:34.077212Z","end":"2026-05-15T15:18:34.549788Z","steps":["trace[240678945] 'agreement among raft nodes before linearized reading' (duration: 472.508531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:34.5498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:33.339134Z","time spent":"1.210535263s","remote":"127.0.0.1:55422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:18:34.549829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:34.077194Z","time spent":"472.629395ms","remote":"127.0.0.1:55200","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-05-15T15:18:34.54986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.50152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-15T15:18:34.549911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.805003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-15T15:18:34.549957Z","caller":"traceutil/trace.go:171","msg":"trace[141940021] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:637; }","duration":"273.931458ms","start":"2026-05-15T15:18:34.276015Z","end":"2026-05-15T15:18:34.549946Z","steps":["trace[141940021] 'agreement among raft nodes before linearized reading' (duration: 273.72416ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:34.549917Z","caller":"traceutil/trace.go:171","msg":"trace[555258059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:637; }","duration":"272.580512ms","start":"2026-05-15T15:18:34.277322Z","end":"2026-05-15T15:18:34.549902Z","steps":["trace[555258059] 'agreement among raft nodes before linearized reading' (duration: 272.427266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:34.550019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.040952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-15T15:18:34.550019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.885846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-15T15:18:34.55005Z","caller":"traceutil/trace.go:171","msg":"trace[1830124249] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:637; }","duration":"273.093944ms","start":"2026-05-15T15:18:34.276947Z","end":"2026-05-15T15:18:34.550041Z","steps":["trace[1830124249] 'agreement among raft nodes before linearized reading' (duration: 273.013521ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:34.550056Z","caller":"traceutil/trace.go:171","msg":"trace[76772564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:637; }","duration":"272.974729ms","start":"2026-05-15T15:18:34.27707Z","end":"2026-05-15T15:18:34.550045Z","steps":["trace[76772564] 'agreement among raft nodes before linearized reading' (duration: 272.875905ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:39.915385Z","caller":"traceutil/trace.go:171","msg":"trace[878583694] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"198.87479ms","start":"2026-05-15T15:18:39.716485Z","end":"2026-05-15T15:18:39.915359Z","steps":["trace[878583694] 'process raft request' (duration: 198.638401ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:48.429879Z","caller":"traceutil/trace.go:171","msg":"trace[1535666027] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:806; }","duration":"350.663616ms","start":"2026-05-15T15:18:48.079195Z","end":"2026-05-15T15:18:48.429858Z","steps":["trace[1535666027] 'read index received' (duration: 350.528511ms)","trace[1535666027] 'applied index is now lower than readState.Index' (duration: 134.215µs)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:18:48.429993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.79002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:18:48.43001Z","caller":"traceutil/trace.go:171","msg":"trace[1718003140] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"356.2346ms","start":"2026-05-15T15:18:48.073738Z","end":"2026-05-15T15:18:48.429972Z","steps":["trace[1718003140] 'process raft request' (duration: 356.003102ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:48.430059Z","caller":"traceutil/trace.go:171","msg":"trace[2097245296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:778; }","duration":"350.831691ms","start":"2026-05-15T15:18:48.079181Z","end":"2026-05-15T15:18:48.430013Z","steps":["trace[2097245296] 'agreement among raft nodes before linearized reading' (duration: 350.764769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:48.430103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:48.079133Z","time spent":"350.962756ms","remote":"127.0.0.1:55200","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-05-15T15:18:48.430179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:48.073713Z","time spent":"356.386125ms","remote":"127.0.0.1:55458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:18:51.579108Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052698327859107,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-15T15:18:52.079316Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052698327859107,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-15T15:18:52.248002Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.470770333s","expected-duration":"1s"} {"level":"info","ts":"2026-05-15T15:18:52.252623Z","caller":"traceutil/trace.go:171","msg":"trace[1560884333] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"1.475436009s","start":"2026-05-15T15:18:50.777168Z","end":"2026-05-15T15:18:52.252604Z","steps":["trace[1560884333] 'process raft request' (duration: 1.471030322s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:52.252764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:50.77715Z","time spent":"1.475559773s","remote":"127.0.0.1:55234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:18:52.259475Z","caller":"traceutil/trace.go:171","msg":"trace[100150453] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:828; }","duration":"1.180871762s","start":"2026-05-15T15:18:51.078581Z","end":"2026-05-15T15:18:52.259452Z","steps":["trace[100150453] 'read index received' (duration: 1.169629536s)","trace[100150453] 'applied index is now lower than readState.Index' (duration: 11.241256ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:18:52.259604Z","caller":"traceutil/trace.go:171","msg":"trace[159080549] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"590.971637ms","start":"2026-05-15T15:18:51.668618Z","end":"2026-05-15T15:18:52.25959Z","steps":["trace[159080549] 'process raft request' (duration: 590.733319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:52.259751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.681999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-15T15:18:52.259904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:51.668583Z","time spent":"591.053299ms","remote":"127.0.0.1:55422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T15:18:52.259934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.379254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-15T15:18:52.259926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.324886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-15T15:18:52.260003Z","caller":"traceutil/trace.go:171","msg":"trace[1725984745] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:799; }","duration":"463.518499ms","start":"2026-05-15T15:18:51.796465Z","end":"2026-05-15T15:18:52.259984Z","steps":["trace[1725984745] 'agreement among raft nodes before linearized reading' (duration: 463.336883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:52.260037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:51.796444Z","time spent":"463.5843ms","remote":"127.0.0.1:55400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-05-15T15:18:52.260052Z","caller":"traceutil/trace.go:171","msg":"trace[2106773306] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:799; }","duration":"850.484041ms","start":"2026-05-15T15:18:51.409548Z","end":"2026-05-15T15:18:52.260032Z","steps":["trace[2106773306] 'agreement among raft nodes before linearized reading' (duration: 850.335756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:52.260043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.181389239s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-15T15:18:52.260114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:51.409524Z","time spent":"850.572054ms","remote":"127.0.0.1:55458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-05-15T15:18:52.259828Z","caller":"traceutil/trace.go:171","msg":"trace[1244672106] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:799; }","duration":"942.828563ms","start":"2026-05-15T15:18:51.31699Z","end":"2026-05-15T15:18:52.259818Z","steps":["trace[1244672106] 'agreement among raft nodes before linearized reading' (duration: 942.631067ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:18:52.260218Z","caller":"traceutil/trace.go:171","msg":"trace[550264675] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:799; }","duration":"1.181610996s","start":"2026-05-15T15:18:51.078537Z","end":"2026-05-15T15:18:52.260148Z","steps":["trace[550264675] 'agreement among raft nodes before linearized reading' (duration: 1.181386169s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:18:52.260357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:51.07852Z","time spent":"1.181820224s","remote":"127.0.0.1:55200","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-05-15T15:18:52.260402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:18:51.31697Z","time spent":"943.319549ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-05-15T15:19:06.082209Z","caller":"traceutil/trace.go:171","msg":"trace[1900968099] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"239.345201ms","start":"2026-05-15T15:19:05.842847Z","end":"2026-05-15T15:19:06.082192Z","steps":["trace[1900968099] 'process raft request' (duration: 239.238168ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:19:06.086738Z","caller":"traceutil/trace.go:171","msg":"trace[564664545] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:995; }","duration":"104.259922ms","start":"2026-05-15T15:19:05.982455Z","end":"2026-05-15T15:19:06.086715Z","steps":["trace[564664545] 'read index received' (duration: 99.611138ms)","trace[564664545] 'applied index is now lower than readState.Index' (duration: 4.647654ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:19:06.086855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.386717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:19:06.086895Z","caller":"traceutil/trace.go:171","msg":"trace[524597375] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:963; }","duration":"104.480909ms","start":"2026-05-15T15:19:05.982407Z","end":"2026-05-15T15:19:06.086887Z","steps":["trace[524597375] 'agreement among raft nodes before linearized reading' (duration: 104.393677ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:19:49.07465Z","caller":"traceutil/trace.go:171","msg":"trace[1012791084] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"140.969084ms","start":"2026-05-15T15:19:48.933651Z","end":"2026-05-15T15:19:49.07462Z","steps":["trace[1012791084] 'process raft request' (duration: 139.75351ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:19:49.074787Z","caller":"traceutil/trace.go:171","msg":"trace[655921723] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"139.019685ms","start":"2026-05-15T15:19:48.935746Z","end":"2026-05-15T15:19:49.074766Z","steps":["trace[655921723] 'process raft request' (duration: 138.833261ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:19:49.07485Z","caller":"traceutil/trace.go:171","msg":"trace[607564454] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"112.950028ms","start":"2026-05-15T15:19:48.961861Z","end":"2026-05-15T15:19:49.074811Z","steps":["trace[607564454] 'process raft request' (duration: 112.795855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:19:49.075149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.102546ms","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-05-15T15:19:49.075232Z","caller":"traceutil/trace.go:171","msg":"trace[1927846276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1123; }","duration":"139.215798ms","start":"2026-05-15T15:19:48.935991Z","end":"2026-05-15T15:19:49.075207Z","steps":["trace[1927846276] 'agreement among raft nodes before linearized reading' (duration: 138.950263ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:19:49.075308Z","caller":"traceutil/trace.go:171","msg":"trace[1239381737] linearizableReadLoop","detail":"{readStateIndex:1166; appliedIndex:1164; }","duration":"138.707689ms","start":"2026-05-15T15:19:48.936015Z","end":"2026-05-15T15:19:49.074723Z","steps":["trace[1239381737] 'read index received' (duration: 137.406943ms)","trace[1239381737] 'applied index is now lower than readState.Index' (duration: 1.298886ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:19:49.075746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.308621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-15T15:19:49.075804Z","caller":"traceutil/trace.go:171","msg":"trace[700427189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1123; }","duration":"138.462245ms","start":"2026-05-15T15:19:48.937326Z","end":"2026-05-15T15:19:49.075789Z","steps":["trace[700427189] 'agreement among raft nodes before linearized reading' (duration: 138.22517ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:20:07.501751Z","caller":"traceutil/trace.go:171","msg":"trace[1498211302] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"278.418628ms","start":"2026-05-15T15:20:07.223306Z","end":"2026-05-15T15:20:07.501725Z","steps":["trace[1498211302] 'process raft request' (duration: 181.042549ms)","trace[1498211302] 'compare' (duration: 97.170346ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:20:07.501975Z","caller":"traceutil/trace.go:171","msg":"trace[694984749] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"277.228028ms","start":"2026-05-15T15:20:07.224724Z","end":"2026-05-15T15:20:07.501952Z","steps":["trace[694984749] 'process raft request' (duration: 276.955113ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:20:07.502208Z","caller":"traceutil/trace.go:171","msg":"trace[800037457] linearizableReadLoop","detail":"{readStateIndex:1233; appliedIndex:1230; }","duration":"260.806993ms","start":"2026-05-15T15:20:07.241378Z","end":"2026-05-15T15:20:07.502185Z","steps":["trace[800037457] 'read index received' (duration: 162.825354ms)","trace[800037457] 'applied index is now lower than readState.Index' (duration: 97.979849ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:20:07.502503Z","caller":"traceutil/trace.go:171","msg":"trace[966364514] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"276.556397ms","start":"2026-05-15T15:20:07.225937Z","end":"2026-05-15T15:20:07.502493Z","steps":["trace[966364514] 'process raft request' (duration: 275.952476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:20:07.502517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.417865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-15T15:20:07.502666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.135569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4219"} {"level":"info","ts":"2026-05-15T15:20:07.502865Z","caller":"traceutil/trace.go:171","msg":"trace[17929545] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:1185; }","duration":"261.485725ms","start":"2026-05-15T15:20:07.241339Z","end":"2026-05-15T15:20:07.502825Z","steps":["trace[17929545] 'agreement among raft nodes before linearized reading' (duration: 261.044098ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:20:07.502725Z","caller":"traceutil/trace.go:171","msg":"trace[1139685959] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1185; }","duration":"189.676839ms","start":"2026-05-15T15:20:07.313028Z","end":"2026-05-15T15:20:07.502705Z","steps":["trace[1139685959] 'agreement among raft nodes before linearized reading' (duration: 189.422605ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:20:31.762786Z","caller":"traceutil/trace.go:171","msg":"trace[1781996300] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"138.209907ms","start":"2026-05-15T15:20:31.624544Z","end":"2026-05-15T15:20:31.762754Z","steps":["trace[1781996300] 'process raft request' (duration: 40.969939ms)","trace[1781996300] 'compare' (duration: 97.110346ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:20:31.769015Z","caller":"traceutil/trace.go:171","msg":"trace[830235036] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"144.260098ms","start":"2026-05-15T15:20:31.62474Z","end":"2026-05-15T15:20:31.769001Z","steps":["trace[830235036] 'process raft request' (duration: 144.193637ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:20:33.638698Z","caller":"traceutil/trace.go:171","msg":"trace[801689590] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"169.475853ms","start":"2026-05-15T15:20:33.469192Z","end":"2026-05-15T15:20:33.638667Z","steps":["trace[801689590] 'process raft request' (duration: 166.033361ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:20:35.467414Z","caller":"traceutil/trace.go:171","msg":"trace[686257375] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"110.929641ms","start":"2026-05-15T15:20:35.356454Z","end":"2026-05-15T15:20:35.467384Z","steps":["trace[686257375] 'process raft request' (duration: 110.764828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:20:35.813939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.420429ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-15T15:20:35.814084Z","caller":"traceutil/trace.go:171","msg":"trace[994933994] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1341; }","duration":"133.733649ms","start":"2026-05-15T15:20:35.68033Z","end":"2026-05-15T15:20:35.814064Z","steps":["trace[994933994] 'read index received' (duration: 31.821µs)","trace[994933994] 'applied index is now lower than readState.Index' (duration: 133.697208ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T15:20:35.814189Z","caller":"traceutil/trace.go:171","msg":"trace[85744172] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"164.692688ms","start":"2026-05-15T15:20:35.649459Z","end":"2026-05-15T15:20:35.814152Z","steps":["trace[85744172] 'process raft request' (duration: 29.948544ms)","trace[85744172] 'compare' (duration: 134.287277ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:20:35.81451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.171806ms","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-05-15T15:20:35.814593Z","caller":"traceutil/trace.go:171","msg":"trace[1507962468] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1288; }","duration":"134.283227ms","start":"2026-05-15T15:20:35.680295Z","end":"2026-05-15T15:20:35.814578Z","steps":["trace[1507962468] 'agreement among raft nodes before linearized reading' (duration: 134.005463ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:20:41.465226Z","caller":"traceutil/trace.go:171","msg":"trace[1235228919] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"119.818254ms","start":"2026-05-15T15:20:41.345373Z","end":"2026-05-15T15:20:41.465191Z","steps":["trace[1235228919] 'process raft request' (duration: 119.596881ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T15:20:48.339162Z","caller":"traceutil/trace.go:171","msg":"trace[1081825423] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"382.244145ms","start":"2026-05-15T15:20:47.956886Z","end":"2026-05-15T15:20:48.33913Z","steps":["trace[1081825423] 'process raft request' (duration: 382.086953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T15:20:48.339345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T15:20:47.956862Z","time spent":"382.401317ms","remote":"127.0.0.1:55458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T15:20:48.339635Z","caller":"traceutil/trace.go:171","msg":"trace[866519620] linearizableReadLoop","detail":"{readStateIndex:1411; appliedIndex:1411; }","duration":"263.549956ms","start":"2026-05-15T15:20:48.076073Z","end":"2026-05-15T15:20:48.339623Z","steps":["trace[866519620] 'read index received' (duration: 263.543536ms)","trace[866519620] 'applied index is now lower than readState.Index' (duration: 5.26µs)"],"step_count":2} {"level":"warn","ts":"2026-05-15T15:20:48.339793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.697828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T15:20:48.339905Z","caller":"traceutil/trace.go:171","msg":"trace[1787049705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1354; }","duration":"263.848291ms","start":"2026-05-15T15:20:48.076028Z","end":"2026-05-15T15:20:48.339876Z","steps":["trace[1787049705] 'agreement among raft nodes before linearized reading' (duration: 263.671008ms)"],"step_count":1}