{"level":"warn","ts":"2026-05-21T19:26:55.270096Z","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-21T19:26:55.27018Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.222: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.19.213.222:2380","--initial-cluster=instance=https://199.19.213.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.222: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-21T19:26:55.270364Z","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-21T19:26:55.270379Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.222:2380"]} {"level":"info","ts":"2026-05-21T19:26:55.270398Z","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-21T19:26:55.271073Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.222:2379"]} {"level":"info","ts":"2026-05-21T19:26:55.271152Z","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.19.213.222:2380"],"listen-peer-urls":["https://199.19.213.222:2380"],"advertise-client-urls":["https://199.19.213.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.222: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-21T19:26:55.320813Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"49.431278ms"} {"level":"info","ts":"2026-05-21T19:26:55.40518Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b78f8b1ca6063edc","cluster-id":"eff609df5818f5a9"} {"level":"info","ts":"2026-05-21T19:26:55.405367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc switched to configuration voters=()"} {"level":"info","ts":"2026-05-21T19:26:55.405413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became follower at term 0"} {"level":"info","ts":"2026-05-21T19:26:55.405454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b78f8b1ca6063edc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-21T19:26:55.405483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became follower at term 1"} {"level":"info","ts":"2026-05-21T19:26:55.405607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc switched to configuration voters=(13226943585771208412)"} {"level":"warn","ts":"2026-05-21T19:26:55.523913Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-21T19:26:55.533764Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-21T19:26:55.540459Z","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-21T19:26:55.56118Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b78f8b1ca6063edc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-21T19:26:55.56212Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b78f8b1ca6063edc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-21T19:26:55.563166Z","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-21T19:26:55.563512Z","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-21T19:26:55.56524Z","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-21T19:26:55.568152Z","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-21T19:26:55.56852Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b78f8b1ca6063edc","initial-advertise-peer-urls":["https://199.19.213.222:2380"],"listen-peer-urls":["https://199.19.213.222:2380"],"advertise-client-urls":["https://199.19.213.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-21T19:26:55.568582Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-21T19:26:55.568825Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.222:2380"} {"level":"info","ts":"2026-05-21T19:26:55.568874Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.222:2380"} {"level":"info","ts":"2026-05-21T19:26:55.577525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc switched to configuration voters=(13226943585771208412)"} {"level":"info","ts":"2026-05-21T19:26:55.57774Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"eff609df5818f5a9","local-member-id":"b78f8b1ca6063edc","added-peer-id":"b78f8b1ca6063edc","added-peer-peer-urls":["https://199.19.213.222:2380"]} {"level":"info","ts":"2026-05-21T19:26:56.005872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc is starting a new election at term 1"} {"level":"info","ts":"2026-05-21T19:26:56.005987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became pre-candidate at term 1"} {"level":"info","ts":"2026-05-21T19:26:56.006028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc received MsgPreVoteResp from b78f8b1ca6063edc at term 1"} {"level":"info","ts":"2026-05-21T19:26:56.006054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became candidate at term 2"} {"level":"info","ts":"2026-05-21T19:26:56.006067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc received MsgVoteResp from b78f8b1ca6063edc at term 2"} {"level":"info","ts":"2026-05-21T19:26:56.006084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became leader at term 2"} {"level":"info","ts":"2026-05-21T19:26:56.006101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b78f8b1ca6063edc elected leader b78f8b1ca6063edc at term 2"} {"level":"info","ts":"2026-05-21T19:26:56.013213Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b78f8b1ca6063edc","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.222:2379]}","request-path":"/0/members/b78f8b1ca6063edc/attributes","cluster-id":"eff609df5818f5a9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-21T19:26:56.013284Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T19:26:56.01359Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T19:26:56.014147Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T19:26:56.014327Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-21T19:26:56.014368Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-21T19:26:56.017622Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.222:2379"} {"level":"info","ts":"2026-05-21T19:26:56.017689Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-21T19:26:56.032451Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"eff609df5818f5a9","local-member-id":"b78f8b1ca6063edc","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T19:26:56.03259Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T19:26:56.032623Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-21T19:26:57.945854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.228178ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-21T19:26:57.946347Z","caller":"traceutil/trace.go:171","msg":"trace[1768726149] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"405.375363ms","start":"2026-05-21T19:26:57.540893Z","end":"2026-05-21T19:26:57.946268Z","steps":["trace[1768726149] 'process raft request' (duration: 405.107299ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:26:57.946426Z","caller":"traceutil/trace.go:171","msg":"trace[1237127376] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"404.797023ms","start":"2026-05-21T19:26:57.541564Z","end":"2026-05-21T19:26:57.946361Z","steps":["trace[1237127376] 'process raft request' (duration: 404.532459ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:26:57.946498Z","caller":"traceutil/trace.go:171","msg":"trace[468638241] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"387.557157ms","start":"2026-05-21T19:26:57.55891Z","end":"2026-05-21T19:26:57.946467Z","steps":["trace[468638241] 'process raft request' (duration: 387.518606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:57.946605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:57.558899Z","time spent":"387.661198ms","remote":"127.0.0.1:36122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:26:57.946622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:57.540884Z","time spent":"405.630256ms","remote":"127.0.0.1:36248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":956,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:26:57.946694Z","caller":"traceutil/trace.go:171","msg":"trace[1911756512] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"405.705068ms","start":"2026-05-21T19:26:57.540969Z","end":"2026-05-21T19:26:57.946674Z","steps":["trace[1911756512] 'process raft request' (duration: 405.091329ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:26:57.946689Z","caller":"traceutil/trace.go:171","msg":"trace[1316497799] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"406.857394ms","start":"2026-05-21T19:26:57.539799Z","end":"2026-05-21T19:26:57.946657Z","steps":["trace[1316497799] 'process raft request' (duration: 214.194784ms)","trace[1316497799] 'compare' (duration: 191.006045ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:26:57.946785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:57.540959Z","time spent":"405.782489ms","remote":"127.0.0.1:36248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:26:57.946831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:57.539789Z","time spent":"406.992596ms","remote":"127.0.0.1:35828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":357,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:26:57.94663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:57.541552Z","time spent":"404.962015ms","remote":"127.0.0.1:36248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:26:57.947139Z","caller":"traceutil/trace.go:171","msg":"trace[1194967570] linearizableReadLoop","detail":"{readStateIndex:30; appliedIndex:24; }","duration":"392.634735ms","start":"2026-05-21T19:26:57.55447Z","end":"2026-05-21T19:26:57.947105Z","steps":["trace[1194967570] 'read index received' (duration: 199.594008ms)","trace[1194967570] 'applied index is now lower than readState.Index' (duration: 193.038637ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:26:57.947312Z","caller":"traceutil/trace.go:171","msg":"trace[734067856] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"405.521105ms","start":"2026-05-21T19:26:57.541775Z","end":"2026-05-21T19:26:57.947296Z","steps":["trace[734067856] 'process raft request' (duration: 404.477218ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:26:57.947376Z","caller":"traceutil/trace.go:171","msg":"trace[28510529] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"389.223082ms","start":"2026-05-21T19:26:57.558135Z","end":"2026-05-21T19:26:57.947358Z","steps":["trace[28510529] 'process raft request' (duration: 388.206216ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:26:57.947416Z","caller":"traceutil/trace.go:171","msg":"trace[20284808] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"405.2482ms","start":"2026-05-21T19:26:57.542148Z","end":"2026-05-21T19:26:57.947396Z","steps":["trace[20284808] 'process raft request' (duration: 404.144673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:57.948036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:57.542137Z","time spent":"405.813368ms","remote":"127.0.0.1:36248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:26:57.948022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:57.558106Z","time spent":"389.797851ms","remote":"127.0.0.1:36164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":713,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:26:57.947867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:57.541763Z","time spent":"406.025532ms","remote":"127.0.0.1:36248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:26:57.950698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.563431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T19:26:57.95078Z","caller":"traceutil/trace.go:171","msg":"trace[1070942507] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:28; }","duration":"408.702034ms","start":"2026-05-21T19:26:57.54206Z","end":"2026-05-21T19:26:57.950762Z","steps":["trace[1070942507] 'agreement among raft nodes before linearized reading' (duration: 407.709258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:57.95082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:57.542051Z","time spent":"408.760375ms","remote":"127.0.0.1:35810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" "} {"level":"warn","ts":"2026-05-21T19:26:57.951035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.675934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-05-21T19:26:57.951073Z","caller":"traceutil/trace.go:171","msg":"trace[1368533209] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:28; }","duration":"412.725196ms","start":"2026-05-21T19:26:57.538338Z","end":"2026-05-21T19:26:57.951063Z","steps":["trace[1368533209] 'agreement among raft nodes before linearized reading' (duration: 411.487066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:57.951101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:57.538319Z","time spent":"412.776106ms","remote":"127.0.0.1:35836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-21T19:26:57.951962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.852898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T19:26:57.952038Z","caller":"traceutil/trace.go:171","msg":"trace[2120067314] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:28; }","duration":"319.955581ms","start":"2026-05-21T19:26:57.632055Z","end":"2026-05-21T19:26:57.95201Z","steps":["trace[2120067314] 'agreement among raft nodes before linearized reading' (duration: 319.843489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:57.95209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:57.63204Z","time spent":"320.043792ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"info","ts":"2026-05-21T19:26:58.214143Z","caller":"traceutil/trace.go:171","msg":"trace[1409515645] linearizableReadLoop","detail":"{readStateIndex:43; appliedIndex:40; }","duration":"226.499518ms","start":"2026-05-21T19:26:57.987615Z","end":"2026-05-21T19:26:58.214114Z","steps":["trace[1409515645] 'read index received' (duration: 5.253882ms)","trace[1409515645] 'applied index is now lower than readState.Index' (duration: 221.244076ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:26:58.214385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.734291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T19:26:58.214407Z","caller":"traceutil/trace.go:171","msg":"trace[216620342] transaction","detail":"{read_only:false; response_revision:39; number_of_response:1; }","duration":"227.152648ms","start":"2026-05-21T19:26:57.98723Z","end":"2026-05-21T19:26:58.214383Z","steps":["trace[216620342] 'process raft request' (duration: 226.810353ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:26:58.214437Z","caller":"traceutil/trace.go:171","msg":"trace[505313441] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:39; }","duration":"226.866004ms","start":"2026-05-21T19:26:57.987555Z","end":"2026-05-21T19:26:58.214421Z","steps":["trace[505313441] 'agreement among raft nodes before linearized reading' (duration: 226.701471ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:26:58.214656Z","caller":"traceutil/trace.go:171","msg":"trace[621206403] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"227.613615ms","start":"2026-05-21T19:26:57.987003Z","end":"2026-05-21T19:26:58.214617Z","steps":["trace[621206403] 'process raft request' (duration: 195.519583ms)","trace[621206403] 'compare' (duration: 31.32979ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:26:58.545075Z","caller":"traceutil/trace.go:171","msg":"trace[2118373097] linearizableReadLoop","detail":"{readStateIndex:49; appliedIndex:48; }","duration":"190.91873ms","start":"2026-05-21T19:26:58.354129Z","end":"2026-05-21T19:26:58.545048Z","steps":["trace[2118373097] 'read index received' (duration: 162.490103ms)","trace[2118373097] 'applied index is now lower than readState.Index' (duration: 28.427597ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:26:58.545251Z","caller":"traceutil/trace.go:171","msg":"trace[1077320172] transaction","detail":"{read_only:false; response_revision:45; number_of_response:1; }","duration":"192.16079ms","start":"2026-05-21T19:26:58.353079Z","end":"2026-05-21T19:26:58.54524Z","steps":["trace[1077320172] 'process raft request' (duration: 163.625912ms)","trace[1077320172] 'compare' (duration: 28.184172ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:26:58.54545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.208803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T19:26:58.545542Z","caller":"traceutil/trace.go:171","msg":"trace[900275489] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:45; }","duration":"187.323155ms","start":"2026-05-21T19:26:58.358193Z","end":"2026-05-21T19:26:58.545516Z","steps":["trace[900275489] 'agreement among raft nodes before linearized reading' (duration: 187.111302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:58.545628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.145602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T19:26:58.545755Z","caller":"traceutil/trace.go:171","msg":"trace[115392455] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:45; }","duration":"187.330005ms","start":"2026-05-21T19:26:58.358406Z","end":"2026-05-21T19:26:58.545736Z","steps":["trace[115392455] 'agreement among raft nodes before linearized reading' (duration: 187.043361ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:58.545698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.413037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/system-nodes\" ","response":"range_response_count:1 size:1070"} {"level":"info","ts":"2026-05-21T19:26:58.545904Z","caller":"traceutil/trace.go:171","msg":"trace[979834802] range","detail":"{range_begin:/registry/flowschemas/system-nodes; range_end:; response_count:1; response_revision:45; }","duration":"191.776293ms","start":"2026-05-21T19:26:58.354071Z","end":"2026-05-21T19:26:58.545847Z","steps":["trace[979834802] 'agreement among raft nodes before linearized reading' (duration: 191.332377ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:26:58.810124Z","caller":"traceutil/trace.go:171","msg":"trace[1686705625] transaction","detail":"{read_only:false; response_revision:59; number_of_response:1; }","duration":"100.544854ms","start":"2026-05-21T19:26:58.709541Z","end":"2026-05-21T19:26:58.810086Z","steps":["trace[1686705625] 'process raft request' (duration: 84.942424ms)","trace[1686705625] 'compare' (duration: 15.323186ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:27:01.346765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.14891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:27:01.347376Z","caller":"traceutil/trace.go:171","msg":"trace[1956679524] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:0; response_revision:256; }","duration":"160.432113ms","start":"2026-05-21T19:27:01.186503Z","end":"2026-05-21T19:27:01.346935Z","steps":["trace[1956679524] 'range keys from in-memory index tree' (duration: 160.060759ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:27:01.593276Z","caller":"traceutil/trace.go:171","msg":"trace[1558556295] linearizableReadLoop","detail":"{readStateIndex:266; appliedIndex:265; }","duration":"125.116375ms","start":"2026-05-21T19:27:01.468117Z","end":"2026-05-21T19:27:01.593233Z","steps":["trace[1558556295] 'read index received' (duration: 48.535557ms)","trace[1558556295] 'applied index is now lower than readState.Index' (duration: 76.579608ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:27:01.593481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.353198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3492"} {"level":"info","ts":"2026-05-21T19:27:01.593529Z","caller":"traceutil/trace.go:171","msg":"trace[1939817280] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:260; }","duration":"125.445459ms","start":"2026-05-21T19:27:01.468069Z","end":"2026-05-21T19:27:01.593515Z","steps":["trace[1939817280] 'agreement among raft nodes before linearized reading' (duration: 125.286477ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:27:01.593458Z","caller":"traceutil/trace.go:171","msg":"trace[1292644669] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"170.015587ms","start":"2026-05-21T19:27:01.423369Z","end":"2026-05-21T19:27:01.593385Z","steps":["trace[1292644669] 'process raft request' (duration: 93.322548ms)","trace[1292644669] 'compare' (duration: 76.267692ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:27:02.815184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.365546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:27:02.815578Z","caller":"traceutil/trace.go:171","msg":"trace[386244771] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"212.556589ms","start":"2026-05-21T19:27:02.602965Z","end":"2026-05-21T19:27:02.815521Z","steps":["trace[386244771] 'process raft request' (duration: 63.779327ms)","trace[386244771] 'compare' (duration: 148.124202ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:27:03.291817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.589792ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:27:03.292228Z","caller":"traceutil/trace.go:171","msg":"trace[105100106] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"326.805463ms","start":"2026-05-21T19:27:02.965384Z","end":"2026-05-21T19:27:03.292189Z","steps":["trace[105100106] 'process raft request' (duration: 326.659711ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:27:03.292289Z","caller":"traceutil/trace.go:171","msg":"trace[2009820209] transaction","detail":"{read_only:false; number_of_response:0; response_revision:303; }","duration":"326.761343ms","start":"2026-05-21T19:27:02.965473Z","end":"2026-05-21T19:27:03.292234Z","steps":["trace[2009820209] 'process raft request' (duration: 326.633661ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:27:03.292187Z","caller":"traceutil/trace.go:171","msg":"trace[1642463271] transaction","detail":"{read_only:false; number_of_response:0; response_revision:302; }","duration":"346.094311ms","start":"2026-05-21T19:27:02.946046Z","end":"2026-05-21T19:27:03.29214Z","steps":["trace[1642463271] 'process raft request' (duration: 345.921308ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:27:03.292356Z","caller":"traceutil/trace.go:171","msg":"trace[272972020] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"270.041122ms","start":"2026-05-21T19:27:03.022293Z","end":"2026-05-21T19:27:03.292334Z","steps":["trace[272972020] 'process raft request' (duration: 269.988062ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:03.292439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:02.946032Z","time spent":"346.365034ms","remote":"127.0.0.1:35910","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:27:03.292435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:02.965464Z","time spent":"326.910055ms","remote":"127.0.0.1:35920","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:27:03.292279Z","caller":"traceutil/trace.go:171","msg":"trace[1609408126] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"346.788671ms","start":"2026-05-21T19:27:02.945309Z","end":"2026-05-21T19:27:03.292098Z","steps":["trace[1609408126] 'process raft request' (duration: 199.794483ms)","trace[1609408126] 'compare' (duration: 146.39672ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:27:03.29244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:02.96537Z","time spent":"326.963045ms","remote":"127.0.0.1:35920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:27:03.292733Z","caller":"traceutil/trace.go:171","msg":"trace[322310703] linearizableReadLoop","detail":"{readStateIndex:314; appliedIndex:307; }","duration":"275.863029ms","start":"2026-05-21T19:27:03.016853Z","end":"2026-05-21T19:27:03.292716Z","steps":["trace[322310703] 'read index received' (duration: 128.267302ms)","trace[322310703] 'applied index is now lower than readState.Index' (duration: 147.594257ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:27:03.292829Z","caller":"traceutil/trace.go:171","msg":"trace[1768366449] transaction","detail":"{read_only:false; number_of_response:0; response_revision:303; }","duration":"327.122669ms","start":"2026-05-21T19:27:02.965689Z","end":"2026-05-21T19:27:03.292811Z","steps":["trace[1768366449] 'process raft request' (duration: 326.451659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:03.292888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:02.96568Z","time spent":"327.17973ms","remote":"127.0.0.1:35920","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:27:03.292934Z","caller":"traceutil/trace.go:171","msg":"trace[1992733127] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"270.768303ms","start":"2026-05-21T19:27:03.022151Z","end":"2026-05-21T19:27:03.292919Z","steps":["trace[1992733127] 'process raft request' (duration: 270.085073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:03.292871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:02.945283Z","time spent":"347.456211ms","remote":"127.0.0.1:35804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:27:03.293003Z","caller":"traceutil/trace.go:171","msg":"trace[1125054532] transaction","detail":"{read_only:false; number_of_response:0; response_revision:303; }","duration":"326.864886ms","start":"2026-05-21T19:27:02.966128Z","end":"2026-05-21T19:27:03.292993Z","steps":["trace[1125054532] 'process raft request' (duration: 326.032573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:03.293054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:02.966113Z","time spent":"326.914156ms","remote":"127.0.0.1:35920","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:27:03.293145Z","caller":"traceutil/trace.go:171","msg":"trace[526469483] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"326.725554ms","start":"2026-05-21T19:27:02.966408Z","end":"2026-05-21T19:27:03.293134Z","steps":["trace[526469483] 'process raft request' (duration: 325.773439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:03.293194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:02.966397Z","time spent":"326.776764ms","remote":"127.0.0.1:35920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:27:03.293255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.157009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:115"} {"level":"info","ts":"2026-05-21T19:27:03.293304Z","caller":"traceutil/trace.go:171","msg":"trace[691315242] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:306; }","duration":"269.250801ms","start":"2026-05-21T19:27:03.02404Z","end":"2026-05-21T19:27:03.293291Z","steps":["trace[691315242] 'agreement among raft nodes before linearized reading' (duration: 269.155269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:03.293358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.244698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:27:03.293403Z","caller":"traceutil/trace.go:171","msg":"trace[236260655] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:0; response_revision:306; }","duration":"341.32133ms","start":"2026-05-21T19:27:02.952068Z","end":"2026-05-21T19:27:03.293389Z","steps":["trace[236260655] 'agreement among raft nodes before linearized reading' (duration: 341.228289ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:03.293443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:02.95206Z","time spent":"341.376321ms","remote":"127.0.0.1:35938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"info","ts":"2026-05-21T19:27:09.459876Z","caller":"traceutil/trace.go:171","msg":"trace[1301294799] linearizableReadLoop","detail":"{readStateIndex:417; appliedIndex:416; }","duration":"121.935188ms","start":"2026-05-21T19:27:09.337917Z","end":"2026-05-21T19:27:09.459852Z","steps":["trace[1301294799] 'read index received' (duration: 121.722335ms)","trace[1301294799] 'applied index is now lower than readState.Index' (duration: 212.053µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:27:09.460031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.10047ms","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-21T19:27:09.460063Z","caller":"traceutil/trace.go:171","msg":"trace[85957986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:403; }","duration":"122.172381ms","start":"2026-05-21T19:27:09.33788Z","end":"2026-05-21T19:27:09.460053Z","steps":["trace[85957986] 'agreement among raft nodes before linearized reading' (duration: 122.07798ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:27:09.460341Z","caller":"traceutil/trace.go:171","msg":"trace[896312574] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"128.605481ms","start":"2026-05-21T19:27:09.331665Z","end":"2026-05-21T19:27:09.460271Z","steps":["trace[896312574] 'process raft request' (duration: 128.043143ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:27:15.716004Z","caller":"traceutil/trace.go:171","msg":"trace[1172642281] linearizableReadLoop","detail":"{readStateIndex:438; appliedIndex:437; }","duration":"203.316362ms","start":"2026-05-21T19:27:15.512662Z","end":"2026-05-21T19:27:15.715978Z","steps":["trace[1172642281] 'read index received' (duration: 203.120889ms)","trace[1172642281] 'applied index is now lower than readState.Index' (duration: 193.882µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:27:15.716128Z","caller":"traceutil/trace.go:171","msg":"trace[1729081265] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"227.097932ms","start":"2026-05-21T19:27:15.488955Z","end":"2026-05-21T19:27:15.716053Z","steps":["trace[1729081265] 'process raft request' (duration: 226.858889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:15.716167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.490405ms","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-21T19:27:15.716342Z","caller":"traceutil/trace.go:171","msg":"trace[1296806035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:423; }","duration":"203.728958ms","start":"2026-05-21T19:27:15.512597Z","end":"2026-05-21T19:27:15.716326Z","steps":["trace[1296806035] 'agreement among raft nodes before linearized reading' (duration: 203.484874ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:27:19.86577Z","caller":"traceutil/trace.go:171","msg":"trace[1226245604] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"119.569858ms","start":"2026-05-21T19:27:19.74617Z","end":"2026-05-21T19:27:19.86574Z","steps":["trace[1226245604] 'process raft request' (duration: 119.341456ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:27:19.884434Z","caller":"traceutil/trace.go:171","msg":"trace[1642484588] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"136.306979ms","start":"2026-05-21T19:27:19.748096Z","end":"2026-05-21T19:27:19.884403Z","steps":["trace[1642484588] 'process raft request' (duration: 136.148676ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:27:21.335375Z","caller":"traceutil/trace.go:171","msg":"trace[554896291] linearizableReadLoop","detail":"{readStateIndex:453; appliedIndex:452; }","duration":"426.160773ms","start":"2026-05-21T19:27:20.909169Z","end":"2026-05-21T19:27:21.33533Z","steps":["trace[554896291] 'read index received' (duration: 425.94008ms)","trace[554896291] 'applied index is now lower than readState.Index' (duration: 219.333µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:27:21.335428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:20.568688Z","time spent":"766.728061ms","remote":"127.0.0.1:35786","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-21T19:27:21.335575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.388367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:27:21.335622Z","caller":"traceutil/trace.go:171","msg":"trace[1601210515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:436; }","duration":"426.533098ms","start":"2026-05-21T19:27:20.909077Z","end":"2026-05-21T19:27:21.33561Z","steps":["trace[1601210515] 'agreement among raft nodes before linearized reading' (duration: 426.415917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:21.33566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:20.90905Z","time spent":"426.60218ms","remote":"127.0.0.1:35746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:27:21.335846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.262243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"info","ts":"2026-05-21T19:27:21.336004Z","caller":"traceutil/trace.go:171","msg":"trace[322961714] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:436; }","duration":"248.482916ms","start":"2026-05-21T19:27:21.08749Z","end":"2026-05-21T19:27:21.335973Z","steps":["trace[322961714] 'agreement among raft nodes before linearized reading' (duration: 248.113951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:21.83799Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4529669374504710351,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:27:21.954978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.770658ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:27:21.955147Z","caller":"traceutil/trace.go:171","msg":"trace[1490182860] linearizableReadLoop","detail":"{readStateIndex:454; appliedIndex:453; }","duration":"617.810047ms","start":"2026-05-21T19:27:21.337323Z","end":"2026-05-21T19:27:21.955133Z","steps":["trace[1490182860] 'read index received' (duration: 511.709174ms)","trace[1490182860] 'applied index is now lower than readState.Index' (duration: 106.099923ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:27:21.955284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.960869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:27:21.955338Z","caller":"traceutil/trace.go:171","msg":"trace[854665674] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:437; }","duration":"618.0598ms","start":"2026-05-21T19:27:21.33727Z","end":"2026-05-21T19:27:21.95533Z","steps":["trace[854665674] 'agreement among raft nodes before linearized reading' (duration: 617.968269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:21.955379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:21.337246Z","time spent":"618.126481ms","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T19:27:21.955389Z","caller":"traceutil/trace.go:171","msg":"trace[1536116614] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"618.647817ms","start":"2026-05-21T19:27:21.336706Z","end":"2026-05-21T19:27:21.955353Z","steps":["trace[1536116614] 'process raft request' (duration: 512.318671ms)","trace[1536116614] 'compare' (duration: 105.604496ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:27:21.95571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:21.336679Z","time spent":"618.80772ms","remote":"127.0.0.1:35786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:27:26.181607Z","caller":"traceutil/trace.go:171","msg":"trace[891985818] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:495; }","duration":"187.619481ms","start":"2026-05-21T19:27:25.993954Z","end":"2026-05-21T19:27:26.181574Z","steps":["trace[891985818] 'read index received' (duration: 187.441509ms)","trace[891985818] 'applied index is now lower than readState.Index' (duration: 175.982µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:27:26.18173Z","caller":"traceutil/trace.go:171","msg":"trace[1973754676] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"197.442335ms","start":"2026-05-21T19:27:25.984248Z","end":"2026-05-21T19:27:26.18169Z","steps":["trace[1973754676] 'process raft request' (duration: 197.138872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:26.181829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.845143ms","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-21T19:27:26.181898Z","caller":"traceutil/trace.go:171","msg":"trace[94102470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:477; }","duration":"187.965435ms","start":"2026-05-21T19:27:25.993915Z","end":"2026-05-21T19:27:26.18188Z","steps":["trace[94102470] 'agreement among raft nodes before linearized reading' (duration: 187.780753ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:26.205314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.460682ms","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-21T19:27:26.205503Z","caller":"traceutil/trace.go:171","msg":"trace[2041729722] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:478; }","duration":"208.671095ms","start":"2026-05-21T19:27:25.996778Z","end":"2026-05-21T19:27:26.205449Z","steps":["trace[2041729722] 'agreement among raft nodes before linearized reading' (duration: 208.418652ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:27:36.356608Z","caller":"traceutil/trace.go:171","msg":"trace[1461346050] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"115.874665ms","start":"2026-05-21T19:27:36.240707Z","end":"2026-05-21T19:27:36.356581Z","steps":["trace[1461346050] 'process raft request' (duration: 115.672473ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:27:36.356964Z","caller":"traceutil/trace.go:171","msg":"trace[446710248] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:556; }","duration":"104.739605ms","start":"2026-05-21T19:27:36.252159Z","end":"2026-05-21T19:27:36.356899Z","steps":["trace[446710248] 'read index received' (duration: 104.719694ms)","trace[446710248] 'applied index is now lower than readState.Index' (duration: 17.111µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:27:36.357235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.032488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-21T19:27:36.357276Z","caller":"traceutil/trace.go:171","msg":"trace[237177850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:534; }","duration":"105.115109ms","start":"2026-05-21T19:27:36.252149Z","end":"2026-05-21T19:27:36.357264Z","steps":["trace[237177850] 'agreement among raft nodes before linearized reading' (duration: 104.933587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:36.857465Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4529669374504710551,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:27:37.358456Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4529669374504710551,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T19:27:37.374946Z","caller":"traceutil/trace.go:171","msg":"trace[79497157] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"1.023197472s","start":"2026-05-21T19:27:36.351718Z","end":"2026-05-21T19:27:37.374916Z","steps":["trace[79497157] 'process raft request' (duration: 1.023143531s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:37.375206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:36.351713Z","time spent":"1.023329513s","remote":"127.0.0.1:35796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:27:37.375409Z","caller":"traceutil/trace.go:171","msg":"trace[759366337] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.028647565s","start":"2026-05-21T19:27:36.346705Z","end":"2026-05-21T19:27:37.375352Z","steps":["trace[759366337] 'process raft request' (duration: 1.027965678s)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:27:37.375478Z","caller":"traceutil/trace.go:171","msg":"trace[1900093394] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.023802699s","start":"2026-05-21T19:27:36.351652Z","end":"2026-05-21T19:27:37.375455Z","steps":["trace[1900093394] 'process raft request' (duration: 1.023152931s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:37.375556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:36.351632Z","time spent":"1.023893019s","remote":"127.0.0.1:35796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:27:37.375632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:36.346671Z","time spent":"1.028848838s","remote":"127.0.0.1:35796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:27:37.392099Z","caller":"traceutil/trace.go:171","msg":"trace[822916872] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.025334245s","start":"2026-05-21T19:27:36.366738Z","end":"2026-05-21T19:27:37.392072Z","steps":["trace[822916872] 'process raft request' (duration: 1.025122383s)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:27:37.392149Z","caller":"traceutil/trace.go:171","msg":"trace[1182258231] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"1.009224106s","start":"2026-05-21T19:27:36.382904Z","end":"2026-05-21T19:27:37.392128Z","steps":["trace[1182258231] 'process raft request' (duration: 1.009162565s)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:27:37.392265Z","caller":"traceutil/trace.go:171","msg":"trace[1031694701] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.015242566s","start":"2026-05-21T19:27:36.377008Z","end":"2026-05-21T19:27:37.39225Z","steps":["trace[1031694701] 'process raft request' (duration: 1.015002194s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:37.392268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:36.366709Z","time spent":"1.025464786s","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:27:37.392324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:36.376984Z","time spent":"1.015315437s","remote":"127.0.0.1:35796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:27:37.392267Z","caller":"traceutil/trace.go:171","msg":"trace[160751831] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.025334205s","start":"2026-05-21T19:27:36.366882Z","end":"2026-05-21T19:27:37.392216Z","steps":["trace[160751831] 'process raft request' (duration: 1.025090332s)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:27:37.392476Z","caller":"traceutil/trace.go:171","msg":"trace[639567501] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"1.005548261s","start":"2026-05-21T19:27:36.386893Z","end":"2026-05-21T19:27:37.392442Z","steps":["trace[639567501] 'process raft request' (duration: 1.005208558s)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:27:37.392559Z","caller":"traceutil/trace.go:171","msg":"trace[1949976364] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"1.000592034s","start":"2026-05-21T19:27:36.391957Z","end":"2026-05-21T19:27:37.392549Z","steps":["trace[1949976364] 'process raft request' (duration: 1.000319791s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:37.392629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:36.391944Z","time spent":"1.000659544s","remote":"127.0.0.1:35796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:27:37.392652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:36.386862Z","time spent":"1.005683663s","remote":"127.0.0.1:35796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:27:37.392689Z","caller":"traceutil/trace.go:171","msg":"trace[639662062] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"978.168629ms","start":"2026-05-21T19:27:36.414489Z","end":"2026-05-21T19:27:37.392658Z","steps":["trace[639662062] 'process raft request' (duration: 977.988397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:37.392736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:36.414464Z","time spent":"978.250939ms","remote":"127.0.0.1:35796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:27:37.39276Z","caller":"traceutil/trace.go:171","msg":"trace[694193302] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"957.732069ms","start":"2026-05-21T19:27:36.435017Z","end":"2026-05-21T19:27:37.392749Z","steps":["trace[694193302] 'process raft request' (duration: 957.505786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:37.392801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:36.434968Z","time spent":"957.815059ms","remote":"127.0.0.1:35796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:27:37.392845Z","caller":"traceutil/trace.go:171","msg":"trace[115452486] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"848.793736ms","start":"2026-05-21T19:27:36.54404Z","end":"2026-05-21T19:27:37.392834Z","steps":["trace[115452486] 'process raft request' (duration: 848.590312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:37.392918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:36.544006Z","time spent":"848.880496ms","remote":"127.0.0.1:35796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:27:37.392367Z","caller":"traceutil/trace.go:171","msg":"trace[2145166885] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.009708041s","start":"2026-05-21T19:27:36.382625Z","end":"2026-05-21T19:27:37.392333Z","steps":["trace[2145166885] 'process raft request' (duration: 1.009415538s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:37.3933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:36.382609Z","time spent":"1.010665922s","remote":"127.0.0.1:35796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:27:37.393353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:36.382899Z","time spent":"1.009305657s","remote":"127.0.0.1:35796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:27:37.393778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:36.366876Z","time spent":"1.026287796s","remote":"127.0.0.1:35796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:27:37.412425Z","caller":"traceutil/trace.go:171","msg":"trace[296793532] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:556; }","duration":"1.055345967s","start":"2026-05-21T19:27:36.35706Z","end":"2026-05-21T19:27:37.412406Z","steps":["trace[296793532] 'read index received' (duration: 1.017633216s)","trace[296793532] 'applied index is now lower than readState.Index' (duration: 37.710601ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:27:37.412636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.106778635s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-21T19:27:37.412697Z","caller":"traceutil/trace.go:171","msg":"trace[778191222] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:551; }","duration":"1.106903625s","start":"2026-05-21T19:27:36.305781Z","end":"2026-05-21T19:27:37.412684Z","steps":["trace[778191222] 'agreement among raft nodes before linearized reading' (duration: 1.106712733s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:37.41272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:36.305752Z","time spent":"1.106962727s","remote":"127.0.0.1:36010","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-21T19:27:37.441587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.055072922s","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-21T19:27:37.441679Z","caller":"traceutil/trace.go:171","msg":"trace[2014383737] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:558; }","duration":"1.055193133s","start":"2026-05-21T19:27:36.386463Z","end":"2026-05-21T19:27:37.441656Z","steps":["trace[2014383737] 'agreement among raft nodes before linearized reading' (duration: 1.055026891s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:37.441714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:36.386455Z","time spent":"1.055250704s","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T19:27:37.44246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.615457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:27:37.442485Z","caller":"traceutil/trace.go:171","msg":"trace[520905602] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"531.702848ms","start":"2026-05-21T19:27:36.910776Z","end":"2026-05-21T19:27:37.442479Z","steps":["trace[520905602] 'agreement among raft nodes before linearized reading' (duration: 531.667058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:37.442499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:36.910734Z","time spent":"531.761978ms","remote":"127.0.0.1:35746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:27:44.578788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.480301ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:27:44.579003Z","caller":"traceutil/trace.go:171","msg":"trace[258295309] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"784.232839ms","start":"2026-05-21T19:27:43.794741Z","end":"2026-05-21T19:27:44.578974Z","steps":["trace[258295309] 'process raft request' (duration: 361.418514ms)","trace[258295309] 'compare' (duration: 422.265699ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:27:44.579094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:43.794704Z","time spent":"784.353131ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:27:44.636235Z","caller":"traceutil/trace.go:171","msg":"trace[850519134] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:675; }","duration":"624.478176ms","start":"2026-05-21T19:27:44.011727Z","end":"2026-05-21T19:27:44.636205Z","steps":["trace[850519134] 'read index received' (duration: 144.519587ms)","trace[850519134] 'applied index is now lower than readState.Index' (duration: 479.957429ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:27:44.636314Z","caller":"traceutil/trace.go:171","msg":"trace[391545936] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"643.759275ms","start":"2026-05-21T19:27:43.992511Z","end":"2026-05-21T19:27:44.63627Z","steps":["trace[391545936] 'process raft request' (duration: 643.046546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:44.636444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.688439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xwlc9\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-21T19:27:44.636488Z","caller":"traceutil/trace.go:171","msg":"trace[1037873994] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xwlc9; range_end:; response_count:1; response_revision:647; }","duration":"624.78112ms","start":"2026-05-21T19:27:44.011693Z","end":"2026-05-21T19:27:44.636474Z","steps":["trace[1037873994] 'agreement among raft nodes before linearized reading' (duration: 624.671489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:44.636511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:43.992473Z","time spent":"643.935807ms","remote":"127.0.0.1:35822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:27:44.636529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:44.011677Z","time spent":"624.8431ms","remote":"127.0.0.1:35920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-xwlc9\" "} {"level":"warn","ts":"2026-05-21T19:27:44.636972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.299354ms","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-21T19:27:44.637077Z","caller":"traceutil/trace.go:171","msg":"trace[556009099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:647; }","duration":"246.456397ms","start":"2026-05-21T19:27:44.390586Z","end":"2026-05-21T19:27:44.637042Z","steps":["trace[556009099] 'agreement among raft nodes before linearized reading' (duration: 246.093333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:45.411888Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4529669374504710741,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T19:27:45.592737Z","caller":"traceutil/trace.go:171","msg":"trace[1238008492] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"939.44026ms","start":"2026-05-21T19:27:44.653257Z","end":"2026-05-21T19:27:45.592697Z","steps":["trace[1238008492] 'process raft request' (duration: 939.116037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:45.592968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:44.653193Z","time spent":"939.657353ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:27:46.911427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000070978s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-21T19:27:46.911547Z","caller":"traceutil/trace.go:171","msg":"trace[48174585] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00027277s","start":"2026-05-21T19:27:44.911249Z","end":"2026-05-21T19:27:46.911521Z","steps":["trace[48174585] 'agreement among raft nodes before linearized reading' (duration: 2.000108479s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:46.911609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:44.911225Z","time spent":"2.000371411s","remote":"127.0.0.1:35746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/21 19:27:46 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-21T19:27:47.880299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.1540676s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:27:47.880516Z","caller":"traceutil/trace.go:171","msg":"trace[699153944] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"3.215420935s","start":"2026-05-21T19:27:44.665029Z","end":"2026-05-21T19:27:47.88045Z","steps":["trace[699153944] 'process raft request' (duration: 1.060882661s)","trace[699153944] 'compare' (duration: 2.149372567s)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:27:47.880586Z","caller":"traceutil/trace.go:171","msg":"trace[739697942] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"3.186823093s","start":"2026-05-21T19:27:44.693735Z","end":"2026-05-21T19:27:47.880558Z","steps":["trace[739697942] 'process raft request' (duration: 3.186644301s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:47.880658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:44.69371Z","time spent":"3.186923623s","remote":"127.0.0.1:35982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:27:47.880785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:44.665001Z","time spent":"3.215651908s","remote":"127.0.0.1:35920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:27:47.907717Z","caller":"traceutil/trace.go:171","msg":"trace[53963799] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:677; }","duration":"2.996383912s","start":"2026-05-21T19:27:44.911303Z","end":"2026-05-21T19:27:47.907687Z","steps":["trace[53963799] 'read index received' (duration: 681.05688ms)","trace[53963799] 'applied index is now lower than readState.Index' (duration: 2.315325502s)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:27:47.907984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.375069934s","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-21T19:27:47.908026Z","caller":"traceutil/trace.go:171","msg":"trace[638871620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:650; }","duration":"2.375178234s","start":"2026-05-21T19:27:45.532838Z","end":"2026-05-21T19:27:47.908016Z","steps":["trace[638871620] 'agreement among raft nodes before linearized reading' (duration: 2.374999853s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:47.908065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:45.532809Z","time spent":"2.375248275s","remote":"127.0.0.1:36010","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-21T19:27:47.908179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.365308054s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-21T19:27:47.908168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.830986ms","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-21T19:27:47.908217Z","caller":"traceutil/trace.go:171","msg":"trace[1563311687] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:650; }","duration":"2.365385184s","start":"2026-05-21T19:27:45.542825Z","end":"2026-05-21T19:27:47.90821Z","steps":["trace[1563311687] 'agreement among raft nodes before linearized reading' (duration: 2.365208753s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:47.908254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:45.542797Z","time spent":"2.365436236s","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-21T19:27:47.908248Z","caller":"traceutil/trace.go:171","msg":"trace[2071543018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:650; }","duration":"310.045779ms","start":"2026-05-21T19:27:47.598181Z","end":"2026-05-21T19:27:47.908227Z","steps":["trace[2071543018] 'agreement among raft nodes before linearized reading' (duration: 309.746595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:47.908302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:47.59815Z","time spent":"310.14151ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T19:27:47.908555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.366487667s","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-21T19:27:47.908752Z","caller":"traceutil/trace.go:171","msg":"trace[1634244464] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:650; }","duration":"2.366712119s","start":"2026-05-21T19:27:45.542017Z","end":"2026-05-21T19:27:47.908729Z","steps":["trace[1634244464] 'agreement among raft nodes before linearized reading' (duration: 2.366071322s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:47.908913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:45.542006Z","time spent":"2.366891701s","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T19:27:47.908313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.448446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:27:47.909269Z","caller":"traceutil/trace.go:171","msg":"trace[183557412] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"997.420738ms","start":"2026-05-21T19:27:46.911815Z","end":"2026-05-21T19:27:47.909236Z","steps":["trace[183557412] 'agreement among raft nodes before linearized reading' (duration: 996.480967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:47.909367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:46.911785Z","time spent":"997.536559ms","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:28:05.517569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.606904751s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:28:05.517781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.528855818s","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-21T19:28:05.517868Z","caller":"traceutil/trace.go:171","msg":"trace[2132216481] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:813; }","duration":"1.528977929s","start":"2026-05-21T19:28:03.988872Z","end":"2026-05-21T19:28:05.517849Z","steps":["trace[2132216481] 'range keys from in-memory index tree' (duration: 1.528685816s)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:05.51767Z","caller":"traceutil/trace.go:171","msg":"trace[1729909437] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:813; }","duration":"1.607064232s","start":"2026-05-21T19:28:03.910584Z","end":"2026-05-21T19:28:05.517649Z","steps":["trace[1729909437] 'range keys from in-memory index tree' (duration: 1.60680601s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:05.517912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:03.988858Z","time spent":"1.52904515s","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T19:28:05.517938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:03.910566Z","time spent":"1.607352745s","remote":"127.0.0.1:35746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:28:05.517954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.524098509s","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-21T19:28:05.518047Z","caller":"traceutil/trace.go:171","msg":"trace[2051988459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:813; }","duration":"1.52425383s","start":"2026-05-21T19:28:03.993769Z","end":"2026-05-21T19:28:05.518023Z","steps":["trace[2051988459] 'range keys from in-memory index tree' (duration: 1.523880986s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:05.518097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:03.993745Z","time spent":"1.524340601s","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T19:28:05.518528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.374696121s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-hdp9r\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-05-21T19:28:05.518542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.510943235s","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-21T19:28:05.518577Z","caller":"traceutil/trace.go:171","msg":"trace[1940693284] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-hdp9r; range_end:; response_count:1; response_revision:813; }","duration":"1.374781452s","start":"2026-05-21T19:28:04.14378Z","end":"2026-05-21T19:28:05.518561Z","steps":["trace[1940693284] 'range keys from in-memory index tree' (duration: 1.374545619s)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:05.518599Z","caller":"traceutil/trace.go:171","msg":"trace[1850661686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:813; }","duration":"1.511024126s","start":"2026-05-21T19:28:04.007561Z","end":"2026-05-21T19:28:05.518585Z","steps":["trace[1850661686] 'range keys from in-memory index tree' (duration: 1.510791052s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:05.518611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:04.143756Z","time spent":"1.374846892s","remote":"127.0.0.1:35920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3989,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-hdp9r\" "} {"level":"warn","ts":"2026-05-21T19:28:05.518633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:04.007545Z","time spent":"1.511079536s","remote":"127.0.0.1:36010","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-21T19:28:05.518732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.502832312s","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-21T19:28:05.518794Z","caller":"traceutil/trace.go:171","msg":"trace[53622605] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:813; }","duration":"1.502919823s","start":"2026-05-21T19:28:04.015859Z","end":"2026-05-21T19:28:05.518779Z","steps":["trace[53622605] 'range keys from in-memory index tree' (duration: 1.502708331s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:05.518836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:04.015846Z","time spent":"1.502979433s","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T19:28:05.519153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.481780596s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-21T19:28:05.519943Z","caller":"traceutil/trace.go:171","msg":"trace[248849947] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:813; }","duration":"1.482657385s","start":"2026-05-21T19:28:04.037258Z","end":"2026-05-21T19:28:05.519916Z","steps":["trace[248849947] 'range keys from in-memory index tree' (duration: 1.481423812s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:05.520015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:04.037247Z","time spent":"1.482748315s","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T19:28:05.790118Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.477448963s","expected-duration":"1s"} {"level":"info","ts":"2026-05-21T19:28:05.790466Z","caller":"traceutil/trace.go:171","msg":"trace[984367831] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"1.477934567s","start":"2026-05-21T19:28:04.312515Z","end":"2026-05-21T19:28:05.790449Z","steps":["trace[984367831] 'process raft request' (duration: 1.477837196s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:05.790577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:04.312477Z","time spent":"1.478025238s","remote":"127.0.0.1:35910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4905,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:28:05.826964Z","caller":"traceutil/trace.go:171","msg":"trace[635991046] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"695.401696ms","start":"2026-05-21T19:28:05.131544Z","end":"2026-05-21T19:28:05.826945Z","steps":["trace[635991046] 'process raft request' (duration: 688.059482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:05.827088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:05.131495Z","time spent":"695.527678ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:28:05.827822Z","caller":"traceutil/trace.go:171","msg":"trace[1079297537] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"303.333081ms","start":"2026-05-21T19:28:05.524479Z","end":"2026-05-21T19:28:05.827812Z","steps":["trace[1079297537] 'process raft request' (duration: 302.437372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:05.828468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:05.524459Z","time spent":"303.910057ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:28:05.82894Z","caller":"traceutil/trace.go:171","msg":"trace[325357053] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"304.397342ms","start":"2026-05-21T19:28:05.524519Z","end":"2026-05-21T19:28:05.828917Z","steps":["trace[325357053] 'process raft request' (duration: 302.655075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:05.829008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:05.524496Z","time spent":"304.478043ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:28:05.829964Z","caller":"traceutil/trace.go:171","msg":"trace[411216856] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"305.1029ms","start":"2026-05-21T19:28:05.524846Z","end":"2026-05-21T19:28:05.829949Z","steps":["trace[411216856] 'process raft request' (duration: 304.639165ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:05.830033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:05.524827Z","time spent":"305.17597ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:28:05.83034Z","caller":"traceutil/trace.go:171","msg":"trace[55747123] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"305.655694ms","start":"2026-05-21T19:28:05.524673Z","end":"2026-05-21T19:28:05.830328Z","steps":["trace[55747123] 'process raft request' (duration: 304.188089ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:05.830325Z","caller":"traceutil/trace.go:171","msg":"trace[1110988420] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"303.562674ms","start":"2026-05-21T19:28:05.52671Z","end":"2026-05-21T19:28:05.830273Z","steps":["trace[1110988420] 'process raft request' (duration: 302.811347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:05.830404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:05.524655Z","time spent":"305.713485ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:28:05.830551Z","caller":"traceutil/trace.go:171","msg":"trace[1126433552] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"305.841606ms","start":"2026-05-21T19:28:05.524694Z","end":"2026-05-21T19:28:05.830535Z","steps":["trace[1126433552] 'process raft request' (duration: 304.733386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:05.830551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:05.526686Z","time spent":"303.704695ms","remote":"127.0.0.1:35920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:28:05.830601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:05.524683Z","time spent":"305.890417ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:28:05.832118Z","caller":"traceutil/trace.go:171","msg":"trace[1091212915] linearizableReadLoop","detail":"{readStateIndex:851; appliedIndex:849; }","duration":"311.625455ms","start":"2026-05-21T19:28:05.520457Z","end":"2026-05-21T19:28:05.832082Z","steps":["trace[1091212915] 'read index received' (duration: 269.84224ms)","trace[1091212915] 'applied index is now lower than readState.Index' (duration: 41.782245ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:28:05.832386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.919809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:28:05.832432Z","caller":"traceutil/trace.go:171","msg":"trace[1436181551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:821; }","duration":"311.986519ms","start":"2026-05-21T19:28:05.520428Z","end":"2026-05-21T19:28:05.832415Z","steps":["trace[1436181551] 'agreement among raft nodes before linearized reading' (duration: 311.918189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:05.832463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:05.520406Z","time spent":"312.050361ms","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:28:05.832638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.649495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:28:05.832699Z","caller":"traceutil/trace.go:171","msg":"trace[883529073] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:821; }","duration":"150.729736ms","start":"2026-05-21T19:28:05.68195Z","end":"2026-05-21T19:28:05.83268Z","steps":["trace[883529073] 'agreement among raft nodes before linearized reading' (duration: 150.657825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:05.833349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.288853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:28:05.833423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.699951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:28:05.833421Z","caller":"traceutil/trace.go:171","msg":"trace[1944260689] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:821; }","duration":"111.373554ms","start":"2026-05-21T19:28:05.722031Z","end":"2026-05-21T19:28:05.833405Z","steps":["trace[1944260689] 'agreement among raft nodes before linearized reading' (duration: 111.272623ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:05.83345Z","caller":"traceutil/trace.go:171","msg":"trace[994597646] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:821; }","duration":"126.739281ms","start":"2026-05-21T19:28:05.706699Z","end":"2026-05-21T19:28:05.833438Z","steps":["trace[994597646] 'agreement among raft nodes before linearized reading' (duration: 126.709651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:05.833587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.239786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:28:05.83362Z","caller":"traceutil/trace.go:171","msg":"trace[927805886] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:821; }","duration":"127.390117ms","start":"2026-05-21T19:28:05.706213Z","end":"2026-05-21T19:28:05.833603Z","steps":["trace[927805886] 'agreement among raft nodes before linearized reading' (duration: 127.358637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:05.833862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.530679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:28:05.83389Z","caller":"traceutil/trace.go:171","msg":"trace[1077932404] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:821; }","duration":"127.566089ms","start":"2026-05-21T19:28:05.706313Z","end":"2026-05-21T19:28:05.83388Z","steps":["trace[1077932404] 'agreement among raft nodes before linearized reading' (duration: 127.373457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:05.83427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.10858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:28:05.834295Z","caller":"traceutil/trace.go:171","msg":"trace[788177762] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:821; }","duration":"151.15956ms","start":"2026-05-21T19:28:05.683129Z","end":"2026-05-21T19:28:05.834289Z","steps":["trace[788177762] 'agreement among raft nodes before linearized reading' (duration: 150.967868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:05.833383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.282011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:28:05.83485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.090072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:28:05.834874Z","caller":"traceutil/trace.go:171","msg":"trace[2084801200] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:821; }","duration":"152.791476ms","start":"2026-05-21T19:28:05.682075Z","end":"2026-05-21T19:28:05.834867Z","steps":["trace[2084801200] 'agreement among raft nodes before linearized reading' (duration: 151.271091ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:05.834898Z","caller":"traceutil/trace.go:171","msg":"trace[1807459542] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:821; }","duration":"112.163073ms","start":"2026-05-21T19:28:05.722714Z","end":"2026-05-21T19:28:05.834877Z","steps":["trace[1807459542] 'agreement among raft nodes before linearized reading' (duration: 112.074922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:05.834973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.88526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:28:05.834994Z","caller":"traceutil/trace.go:171","msg":"trace[1647085792] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:821; }","duration":"112.923281ms","start":"2026-05-21T19:28:05.722065Z","end":"2026-05-21T19:28:05.834988Z","steps":["trace[1647085792] 'agreement among raft nodes before linearized reading' (duration: 112.8878ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:13.780923Z","caller":"traceutil/trace.go:171","msg":"trace[480815721] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"290.04362ms","start":"2026-05-21T19:28:13.49085Z","end":"2026-05-21T19:28:13.780893Z","steps":["trace[480815721] 'process raft request' (duration: 289.883949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:14.326896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.64593ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:28:14.327377Z","caller":"traceutil/trace.go:171","msg":"trace[1982853065] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"292.701996ms","start":"2026-05-21T19:28:14.034611Z","end":"2026-05-21T19:28:14.327313Z","steps":["trace[1982853065] 'process raft request' (duration: 142.542361ms)","trace[1982853065] 'compare' (duration: 149.339376ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:28:14.857561Z","caller":"traceutil/trace.go:171","msg":"trace[7850752] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"435.698151ms","start":"2026-05-21T19:28:14.421819Z","end":"2026-05-21T19:28:14.857517Z","steps":["trace[7850752] 'process raft request' (duration: 379.297526ms)","trace[7850752] 'compare' (duration: 56.088481ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:28:14.857791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:14.421805Z","time spent":"435.869812ms","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3852,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:28:15.369592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.832594ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:28:15.369726Z","caller":"traceutil/trace.go:171","msg":"trace[1582979964] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"506.250259ms","start":"2026-05-21T19:28:14.863456Z","end":"2026-05-21T19:28:15.369706Z","steps":["trace[1582979964] 'process raft request' (duration: 345.226703ms)","trace[1582979964] 'compare' (duration: 160.691913ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:28:15.369798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:14.863438Z","time spent":"506.322441ms","remote":"127.0.0.1:35804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:28:15.895419Z","caller":"traceutil/trace.go:171","msg":"trace[934961870] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"1.031233338s","start":"2026-05-21T19:28:14.864149Z","end":"2026-05-21T19:28:15.895382Z","steps":["trace[934961870] 'process raft request' (duration: 1.003725589s)","trace[934961870] 'compare' (duration: 27.091915ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:28:15.895644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:14.864121Z","time spent":"1.031410679s","remote":"127.0.0.1:35920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4869,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:28:15.895724Z","caller":"traceutil/trace.go:171","msg":"trace[177953949] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"1.028076656s","start":"2026-05-21T19:28:14.86761Z","end":"2026-05-21T19:28:15.895686Z","steps":["trace[177953949] 'process raft request' (duration: 1.027698862s)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:15.895796Z","caller":"traceutil/trace.go:171","msg":"trace[1995308898] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:953; }","duration":"985.794251ms","start":"2026-05-21T19:28:14.909974Z","end":"2026-05-21T19:28:15.895768Z","steps":["trace[1995308898] 'read index received' (duration: 298.695546ms)","trace[1995308898] 'applied index is now lower than readState.Index' (duration: 687.095095ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:28:15.89591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:14.8676Z","time spent":"1.028208377s","remote":"127.0.0.1:36166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4213,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:28:15.948334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038341015s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:28:15.948449Z","caller":"traceutil/trace.go:171","msg":"trace[1480486595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:918; }","duration":"1.038520058s","start":"2026-05-21T19:28:14.909903Z","end":"2026-05-21T19:28:15.948423Z","steps":["trace[1480486595] 'agreement among raft nodes before linearized reading' (duration: 985.980493ms)","trace[1480486595] 'range keys from in-memory index tree' (duration: 52.396343ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:28:15.948501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:14.909875Z","time spent":"1.038616518s","remote":"127.0.0.1:35746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:28:15.948628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.982029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:28:15.948724Z","caller":"traceutil/trace.go:171","msg":"trace[1800021443] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:919; }","duration":"192.12984ms","start":"2026-05-21T19:28:15.75657Z","end":"2026-05-21T19:28:15.9487Z","steps":["trace[1800021443] 'agreement among raft nodes before linearized reading' (duration: 191.806397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:15.949293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.609046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-h9zgk\" ","response":"range_response_count:1 size:4777"} {"level":"info","ts":"2026-05-21T19:28:15.9494Z","caller":"traceutil/trace.go:171","msg":"trace[1560551902] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-h9zgk; range_end:; response_count:1; response_revision:919; }","duration":"605.770909ms","start":"2026-05-21T19:28:15.343609Z","end":"2026-05-21T19:28:15.94938Z","steps":["trace[1560551902] 'agreement among raft nodes before linearized reading' (duration: 604.777689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:15.949434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:15.343587Z","time spent":"605.83782ms","remote":"127.0.0.1:35920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4801,"request content":"key:\"/registry/pods/kube-system/coredns-67659f764b-h9zgk\" "} {"level":"warn","ts":"2026-05-21T19:28:16.449012Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4529669374504711324,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T19:28:16.520988Z","caller":"traceutil/trace.go:171","msg":"trace[31147216] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"618.651865ms","start":"2026-05-21T19:28:15.902293Z","end":"2026-05-21T19:28:16.520945Z","steps":["trace[31147216] 'process raft request' (duration: 558.550919ms)","trace[31147216] 'compare' (duration: 59.339488ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:28:16.521256Z","caller":"traceutil/trace.go:171","msg":"trace[1969259075] transaction","detail":"{read_only:false; number_of_response:1; response_revision:923; }","duration":"615.15191ms","start":"2026-05-21T19:28:15.906074Z","end":"2026-05-21T19:28:16.521226Z","steps":["trace[1969259075] 'process raft request' (duration: 615.05487ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:16.521429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:15.902265Z","time spent":"618.998448ms","remote":"127.0.0.1:35904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":624,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:28:16.521515Z","caller":"traceutil/trace.go:171","msg":"trace[1672556831] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"619.082819ms","start":"2026-05-21T19:28:15.902422Z","end":"2026-05-21T19:28:16.521505Z","steps":["trace[1672556831] 'process raft request' (duration: 618.392633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:16.521597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:15.902403Z","time spent":"619.152499ms","remote":"127.0.0.1:35804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":723,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:28:16.521461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:15.906059Z","time spent":"615.296942ms","remote":"127.0.0.1:36166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":45,"response count":0,"response size":4263,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:28:16.522037Z","caller":"traceutil/trace.go:171","msg":"trace[1170250513] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"619.04331ms","start":"2026-05-21T19:28:15.902978Z","end":"2026-05-21T19:28:16.522021Z","steps":["trace[1170250513] 'process raft request' (duration: 617.967119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:16.522131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:15.902971Z","time spent":"619.11476ms","remote":"127.0.0.1:36012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1292,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:28:16.523513Z","caller":"traceutil/trace.go:171","msg":"trace[74149019] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"619.181031ms","start":"2026-05-21T19:28:15.903153Z","end":"2026-05-21T19:28:16.522334Z","steps":["trace[74149019] 'process raft request' (duration: 617.871578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:16.523806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:15.903144Z","time spent":"620.477883ms","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3852,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:28:16.972164Z","caller":"traceutil/trace.go:171","msg":"trace[858001761] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:958; }","duration":"1.023883695s","start":"2026-05-21T19:28:15.948251Z","end":"2026-05-21T19:28:16.972134Z","steps":["trace[858001761] 'read index received' (duration: 512.70309ms)","trace[858001761] 'applied index is now lower than readState.Index' (duration: 511.179595ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:28:16.972279Z","caller":"traceutil/trace.go:171","msg":"trace[813723468] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"638.141263ms","start":"2026-05-21T19:28:16.334098Z","end":"2026-05-21T19:28:16.97224Z","steps":["trace[813723468] 'process raft request' (duration: 637.992131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:16.972419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045530927s","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-21T19:28:16.972463Z","caller":"traceutil/trace.go:171","msg":"trace[501230899] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:926; }","duration":"1.045642638s","start":"2026-05-21T19:28:15.926809Z","end":"2026-05-21T19:28:16.972452Z","steps":["trace[501230899] 'agreement among raft nodes before linearized reading' (duration: 1.045463767s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:16.972499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:15.926777Z","time spent":"1.045713619s","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T19:28:16.972482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:16.334069Z","time spent":"638.310364ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:28:16.972706Z","caller":"traceutil/trace.go:171","msg":"trace[286086282] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"756.332185ms","start":"2026-05-21T19:28:16.216361Z","end":"2026-05-21T19:28:16.972693Z","steps":["trace[286086282] 'process raft request' (duration: 755.670449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:16.972681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.053135111s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-21T19:28:16.972765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:16.216312Z","time spent":"756.420215ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:28:16.972921Z","caller":"traceutil/trace.go:171","msg":"trace[1765808160] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"1.014097519s","start":"2026-05-21T19:28:15.958796Z","end":"2026-05-21T19:28:16.972893Z","steps":["trace[1765808160] 'process raft request' (duration: 998.505536ms)","trace[1765808160] 'compare' (duration: 14.030267ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:28:16.973098Z","caller":"traceutil/trace.go:171","msg":"trace[1696167285] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:926; }","duration":"1.053333482s","start":"2026-05-21T19:28:15.919446Z","end":"2026-05-21T19:28:16.972779Z","steps":["trace[1696167285] 'agreement among raft nodes before linearized reading' (duration: 1.052963829s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:16.97318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:15.919426Z","time spent":"1.053734278s","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T19:28:16.97319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:15.958762Z","time spent":"1.014224871s","remote":"127.0.0.1:35920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5068,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:28:17.199171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.01616ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:28:17.1993Z","caller":"traceutil/trace.go:171","msg":"trace[444294314] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:966; }","duration":"227.042406ms","start":"2026-05-21T19:28:16.972238Z","end":"2026-05-21T19:28:17.19928Z","steps":["trace[444294314] 'read index received' (duration: 122.915915ms)","trace[444294314] 'applied index is now lower than readState.Index' (duration: 104.124731ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:28:17.199424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.128530987s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:28:17.199523Z","caller":"traceutil/trace.go:171","msg":"trace[840162037] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:927; }","duration":"1.128668488s","start":"2026-05-21T19:28:16.07083Z","end":"2026-05-21T19:28:17.199498Z","steps":["trace[840162037] 'agreement among raft nodes before linearized reading' (duration: 1.128488007s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:17.19958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.208108912s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-21T19:28:17.199584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:16.070816Z","time spent":"1.128755689s","remote":"127.0.0.1:35822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"info","ts":"2026-05-21T19:28:17.199639Z","caller":"traceutil/trace.go:171","msg":"trace[714685104] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:927; }","duration":"1.208224353s","start":"2026-05-21T19:28:15.9914Z","end":"2026-05-21T19:28:17.199624Z","steps":["trace[714685104] 'agreement among raft nodes before linearized reading' (duration: 1.208006541s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:17.199662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.249483898s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:28:17.199686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:15.991374Z","time spent":"1.208302515s","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-21T19:28:17.199699Z","caller":"traceutil/trace.go:171","msg":"trace[1975770595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:927; }","duration":"1.249556219s","start":"2026-05-21T19:28:15.950128Z","end":"2026-05-21T19:28:17.199684Z","steps":["trace[1975770595] 'agreement among raft nodes before linearized reading' (duration: 1.249505457s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:17.19973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:15.950103Z","time spent":"1.249621869s","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T19:28:17.20011Z","caller":"traceutil/trace.go:171","msg":"trace[531970520] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"665.652424ms","start":"2026-05-21T19:28:16.534425Z","end":"2026-05-21T19:28:17.200077Z","steps":["trace[531970520] 'process raft request' (duration: 560.621594ms)","trace[531970520] 'compare' (duration: 103.869919ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:28:17.199598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.161496168s","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-21T19:28:17.200142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.981439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:28:17.200221Z","caller":"traceutil/trace.go:171","msg":"trace[9284148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:927; }","duration":"1.162157324s","start":"2026-05-21T19:28:16.038045Z","end":"2026-05-21T19:28:17.200202Z","steps":["trace[9284148] 'agreement among raft nodes before linearized reading' (duration: 1.161316776s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:17.200282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:16.038021Z","time spent":"1.162245875s","remote":"127.0.0.1:36010","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-21T19:28:17.200307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:16.534402Z","time spent":"665.792805ms","remote":"127.0.0.1:36166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4186,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:28:17.200269Z","caller":"traceutil/trace.go:171","msg":"trace[310470981] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:927; }","duration":"581.392102ms","start":"2026-05-21T19:28:16.618854Z","end":"2026-05-21T19:28:17.200246Z","steps":["trace[310470981] 'agreement among raft nodes before linearized reading' (duration: 580.908878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:17.200151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.576652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" ","response":"range_response_count:1 size:3867"} {"level":"warn","ts":"2026-05-21T19:28:17.200457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:16.618823Z","time spent":"581.563003ms","remote":"127.0.0.1:36122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":31,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-05-21T19:28:17.200481Z","caller":"traceutil/trace.go:171","msg":"trace[84800793] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-7c96b6546b; range_end:; response_count:1; response_revision:927; }","duration":"669.914135ms","start":"2026-05-21T19:28:16.530536Z","end":"2026-05-21T19:28:17.20045Z","steps":["trace[84800793] 'agreement among raft nodes before linearized reading' (duration: 669.292469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:17.200643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:16.530515Z","time spent":"670.094636ms","remote":"127.0.0.1:36206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":3891,"request content":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" "} {"level":"warn","ts":"2026-05-21T19:28:17.200204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.163656379s","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-21T19:28:17.20082Z","caller":"traceutil/trace.go:171","msg":"trace[388333875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:927; }","duration":"1.164316485s","start":"2026-05-21T19:28:16.03649Z","end":"2026-05-21T19:28:17.200807Z","steps":["trace[388333875] 'agreement among raft nodes before linearized reading' (duration: 1.163617619s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:17.200925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:16.036461Z","time spent":"1.164431036s","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-21T19:28:17.289288Z","caller":"traceutil/trace.go:171","msg":"trace[1681709811] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"309.930801ms","start":"2026-05-21T19:28:16.979318Z","end":"2026-05-21T19:28:17.289249Z","steps":["trace[1681709811] 'process raft request' (duration: 309.672448ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:17.289532Z","caller":"traceutil/trace.go:171","msg":"trace[50754541] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"309.020253ms","start":"2026-05-21T19:28:16.980492Z","end":"2026-05-21T19:28:17.289513Z","steps":["trace[50754541] 'process raft request' (duration: 308.698819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:17.289621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:16.979296Z","time spent":"310.186805ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:28:17.289746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:16.980481Z","time spent":"309.164154ms","remote":"127.0.0.1:35904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":739,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:28:17.289846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.843007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:28:17.289903Z","caller":"traceutil/trace.go:171","msg":"trace[1654740565] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:931; }","duration":"213.959569ms","start":"2026-05-21T19:28:17.07593Z","end":"2026-05-21T19:28:17.28989Z","steps":["trace[1654740565] 'agreement among raft nodes before linearized reading' (duration: 213.855608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:17.290036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.576268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-9sd5j\" ","response":"range_response_count:1 size:4884"} {"level":"info","ts":"2026-05-21T19:28:17.290012Z","caller":"traceutil/trace.go:171","msg":"trace[1847089025] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"309.310535ms","start":"2026-05-21T19:28:16.980562Z","end":"2026-05-21T19:28:17.289872Z","steps":["trace[1847089025] 'process raft request' (duration: 308.805541ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:17.290093Z","caller":"traceutil/trace.go:171","msg":"trace[815189008] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-9sd5j; range_end:; response_count:1; response_revision:931; }","duration":"310.670518ms","start":"2026-05-21T19:28:16.979407Z","end":"2026-05-21T19:28:17.290078Z","steps":["trace[815189008] 'agreement among raft nodes before linearized reading' (duration: 310.486097ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:17.290476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:16.979395Z","time spent":"311.058453ms","remote":"127.0.0.1:35920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4908,"request content":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-9sd5j\" "} {"level":"warn","ts":"2026-05-21T19:28:17.290519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:16.98053Z","time spent":"309.784119ms","remote":"127.0.0.1:36012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1450,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:28:17.289283Z","caller":"traceutil/trace.go:171","msg":"trace[756573826] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"309.910201ms","start":"2026-05-21T19:28:16.979349Z","end":"2026-05-21T19:28:17.289259Z","steps":["trace[756573826] 'process raft request' (duration: 309.79225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:17.291008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:16.979323Z","time spent":"311.603298ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:28:17.554294Z","caller":"traceutil/trace.go:171","msg":"trace[482410056] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"248.445133ms","start":"2026-05-21T19:28:17.305779Z","end":"2026-05-21T19:28:17.554224Z","steps":["trace[482410056] 'process raft request' (duration: 248.274412ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:17.565893Z","caller":"traceutil/trace.go:171","msg":"trace[1926545166] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"259.351869ms","start":"2026-05-21T19:28:17.306519Z","end":"2026-05-21T19:28:17.565871Z","steps":["trace[1926545166] 'process raft request' (duration: 259.203588ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:19.294436Z","caller":"traceutil/trace.go:171","msg":"trace[1920287281] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"125.662762ms","start":"2026-05-21T19:28:19.168747Z","end":"2026-05-21T19:28:19.29441Z","steps":["trace[1920287281] 'process raft request' (duration: 125.477299ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:19.503111Z","caller":"traceutil/trace.go:171","msg":"trace[233856697] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"201.20742ms","start":"2026-05-21T19:28:19.301565Z","end":"2026-05-21T19:28:19.502773Z","steps":["trace[233856697] 'process raft request' (duration: 176.576333ms)","trace[233856697] 'compare' (duration: 24.352404ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:28:19.503393Z","caller":"traceutil/trace.go:171","msg":"trace[7804119] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1006; }","duration":"201.692865ms","start":"2026-05-21T19:28:19.301656Z","end":"2026-05-21T19:28:19.503349Z","steps":["trace[7804119] 'read index received' (duration: 20.123164ms)","trace[7804119] 'applied index is now lower than readState.Index' (duration: 181.563731ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:28:19.503635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.951187ms","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-21T19:28:19.503731Z","caller":"traceutil/trace.go:171","msg":"trace[365795094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:970; }","duration":"202.072018ms","start":"2026-05-21T19:28:19.301629Z","end":"2026-05-21T19:28:19.503701Z","steps":["trace[365795094] 'agreement among raft nodes before linearized reading' (duration: 201.828576ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:19.503787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.23975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-21T19:28:19.503804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.046288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T19:28:19.503844Z","caller":"traceutil/trace.go:171","msg":"trace[1451509103] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:970; }","duration":"201.367242ms","start":"2026-05-21T19:28:19.302462Z","end":"2026-05-21T19:28:19.50383Z","steps":["trace[1451509103] 'agreement among raft nodes before linearized reading' (duration: 201.08659ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:19.503871Z","caller":"traceutil/trace.go:171","msg":"trace[425856582] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:970; }","duration":"201.138088ms","start":"2026-05-21T19:28:19.302716Z","end":"2026-05-21T19:28:19.503854Z","steps":["trace[425856582] 'agreement among raft nodes before linearized reading' (duration: 200.924067ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:19.503284Z","caller":"traceutil/trace.go:171","msg":"trace[1648076986] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"199.404673ms","start":"2026-05-21T19:28:19.303851Z","end":"2026-05-21T19:28:19.503256Z","steps":["trace[1648076986] 'process raft request' (duration: 198.794857ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:19.682391Z","caller":"traceutil/trace.go:171","msg":"trace[1930309963] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"172.732726ms","start":"2026-05-21T19:28:19.509621Z","end":"2026-05-21T19:28:19.682354Z","steps":["trace[1930309963] 'process raft request' (duration: 135.418036ms)","trace[1930309963] 'compare' (duration: 37.053648ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:28:19.682682Z","caller":"traceutil/trace.go:171","msg":"trace[1844783670] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"172.007529ms","start":"2026-05-21T19:28:19.510662Z","end":"2026-05-21T19:28:19.68267Z","steps":["trace[1844783670] 'process raft request' (duration: 171.634596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:25.872731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.059433ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-21T19:28:25.87284Z","caller":"traceutil/trace.go:171","msg":"trace[793714037] linearizableReadLoop","detail":"{readStateIndex:1049; appliedIndex:1048; }","duration":"199.541954ms","start":"2026-05-21T19:28:25.673279Z","end":"2026-05-21T19:28:25.872821Z","steps":["trace[793714037] 'read index received' (duration: 6.293419ms)","trace[793714037] 'applied index is now lower than readState.Index' (duration: 193.246765ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:28:25.873205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.790955ms","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-21T19:28:25.873265Z","caller":"traceutil/trace.go:171","msg":"trace[1298288529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1006; }","duration":"166.858625ms","start":"2026-05-21T19:28:25.706395Z","end":"2026-05-21T19:28:25.873254Z","steps":["trace[1298288529] 'agreement among raft nodes before linearized reading' (duration: 166.669884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:25.873174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.874097ms","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-21T19:28:25.873398Z","caller":"traceutil/trace.go:171","msg":"trace[623423608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1006; }","duration":"200.136089ms","start":"2026-05-21T19:28:25.67323Z","end":"2026-05-21T19:28:25.873366Z","steps":["trace[623423608] 'agreement among raft nodes before linearized reading' (duration: 199.769056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:25.87399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.267936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T19:28:25.874048Z","caller":"traceutil/trace.go:171","msg":"trace[1598180505] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1006; }","duration":"139.405177ms","start":"2026-05-21T19:28:25.734631Z","end":"2026-05-21T19:28:25.874036Z","steps":["trace[1598180505] 'agreement among raft nodes before linearized reading' (duration: 138.65625ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:28.163106Z","caller":"traceutil/trace.go:171","msg":"trace[863978999] linearizableReadLoop","detail":"{readStateIndex:1057; appliedIndex:1056; }","duration":"214.017203ms","start":"2026-05-21T19:28:27.94903Z","end":"2026-05-21T19:28:28.163047Z","steps":["trace[863978999] 'read index received' (duration: 213.831972ms)","trace[863978999] 'applied index is now lower than readState.Index' (duration: 184.071µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:28:28.163688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.650209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:28:28.164015Z","caller":"traceutil/trace.go:171","msg":"trace[1371086852] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1014; }","duration":"215.041883ms","start":"2026-05-21T19:28:27.948951Z","end":"2026-05-21T19:28:28.163993Z","steps":["trace[1371086852] 'agreement among raft nodes before linearized reading' (duration: 214.629259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:28.163709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.036767ms","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-21T19:28:28.164278Z","caller":"traceutil/trace.go:171","msg":"trace[721419937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1014; }","duration":"193.667113ms","start":"2026-05-21T19:28:27.970592Z","end":"2026-05-21T19:28:28.164259Z","steps":["trace[721419937] 'agreement among raft nodes before linearized reading' (duration: 192.935386ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:28.163726Z","caller":"traceutil/trace.go:171","msg":"trace[248143390] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"230.502667ms","start":"2026-05-21T19:28:27.933177Z","end":"2026-05-21T19:28:28.163679Z","steps":["trace[248143390] 'process raft request' (duration: 229.7009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:28.163809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.423071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T19:28:28.164526Z","caller":"traceutil/trace.go:171","msg":"trace[682382012] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1014; }","duration":"193.166838ms","start":"2026-05-21T19:28:27.971318Z","end":"2026-05-21T19:28:28.164485Z","steps":["trace[682382012] 'agreement among raft nodes before linearized reading' (duration: 192.274039ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:29.889364Z","caller":"traceutil/trace.go:171","msg":"trace[1578794232] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"107.798565ms","start":"2026-05-21T19:28:29.781523Z","end":"2026-05-21T19:28:29.889322Z","steps":["trace[1578794232] 'process raft request' (duration: 107.592643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:30.382834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.542281ms","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-21T19:28:30.382924Z","caller":"traceutil/trace.go:171","msg":"trace[245126272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1021; }","duration":"118.684702ms","start":"2026-05-21T19:28:30.26422Z","end":"2026-05-21T19:28:30.382904Z","steps":["trace[245126272] 'range keys from in-memory index tree' (duration: 118.287619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:30.382956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.768395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T19:28:30.383063Z","caller":"traceutil/trace.go:171","msg":"trace[314464333] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1021; }","duration":"116.913316ms","start":"2026-05-21T19:28:30.266123Z","end":"2026-05-21T19:28:30.383037Z","steps":["trace[314464333] 'range keys from in-memory index tree' (duration: 116.604264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:30.888859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.054883ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:28:30.889002Z","caller":"traceutil/trace.go:171","msg":"trace[1820242267] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"218.918175ms","start":"2026-05-21T19:28:30.670059Z","end":"2026-05-21T19:28:30.888977Z","steps":["trace[1820242267] 'process raft request' (duration: 109.64028ms)","trace[1820242267] 'compare' (duration: 108.895461ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:28:31.765107Z","caller":"traceutil/trace.go:171","msg":"trace[1113594272] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"310.357373ms","start":"2026-05-21T19:28:31.454719Z","end":"2026-05-21T19:28:31.765076Z","steps":["trace[1113594272] 'process raft request' (duration: 309.9401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:31.765253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:31.454703Z","time spent":"310.476186ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:28:36.852273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.935043ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:28:36.852377Z","caller":"traceutil/trace.go:171","msg":"trace[1086250125] linearizableReadLoop","detail":"{readStateIndex:1087; appliedIndex:1086; }","duration":"350.471418ms","start":"2026-05-21T19:28:36.501887Z","end":"2026-05-21T19:28:36.852358Z","steps":["trace[1086250125] 'read index received' (duration: 206.318062ms)","trace[1086250125] 'applied index is now lower than readState.Index' (duration: 144.152216ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:28:36.85252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.626529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T19:28:36.852557Z","caller":"traceutil/trace.go:171","msg":"trace[2133161305] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1042; }","duration":"350.692079ms","start":"2026-05-21T19:28:36.501854Z","end":"2026-05-21T19:28:36.852546Z","steps":["trace[2133161305] 'agreement among raft nodes before linearized reading' (duration: 350.551958ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:36.852524Z","caller":"traceutil/trace.go:171","msg":"trace[2016327998] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"359.529241ms","start":"2026-05-21T19:28:36.492962Z","end":"2026-05-21T19:28:36.852492Z","steps":["trace[2016327998] 'process raft request' (duration: 215.274995ms)","trace[2016327998] 'compare' (duration: 143.812692ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:28:36.852601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:36.501841Z","time spent":"350.75205ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T19:28:36.852675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:36.49293Z","time spent":"359.675732ms","remote":"127.0.0.1:35910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:28:46.642873Z","caller":"traceutil/trace.go:171","msg":"trace[1206094381] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"102.837934ms","start":"2026-05-21T19:28:46.539992Z","end":"2026-05-21T19:28:46.642829Z","steps":["trace[1206094381] 'process raft request' (duration: 102.654601ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:52.080178Z","caller":"traceutil/trace.go:171","msg":"trace[1638027426] linearizableReadLoop","detail":"{readStateIndex:1146; appliedIndex:1145; }","duration":"104.966919ms","start":"2026-05-21T19:28:51.975182Z","end":"2026-05-21T19:28:52.080149Z","steps":["trace[1638027426] 'read index received' (duration: 104.810238ms)","trace[1638027426] 'applied index is now lower than readState.Index' (duration: 154.701µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:28:52.080473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.248641ms","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-21T19:28:52.080572Z","caller":"traceutil/trace.go:171","msg":"trace[930290817] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1097; }","duration":"105.400472ms","start":"2026-05-21T19:28:51.975156Z","end":"2026-05-21T19:28:52.080556Z","steps":["trace[930290817] 'agreement among raft nodes before linearized reading' (duration: 105.09733ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:52.080811Z","caller":"traceutil/trace.go:171","msg":"trace[799384607] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"106.818994ms","start":"2026-05-21T19:28:51.973386Z","end":"2026-05-21T19:28:52.080205Z","steps":["trace[799384607] 'process raft request' (duration: 106.572122ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:52.542004Z","caller":"traceutil/trace.go:171","msg":"trace[1032902761] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"163.056867ms","start":"2026-05-21T19:28:52.378921Z","end":"2026-05-21T19:28:52.541977Z","steps":["trace[1032902761] 'process raft request' (duration: 162.884575ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:56.247557Z","caller":"traceutil/trace.go:171","msg":"trace[1952607268] linearizableReadLoop","detail":"{readStateIndex:1158; appliedIndex:1157; }","duration":"106.472522ms","start":"2026-05-21T19:28:56.141049Z","end":"2026-05-21T19:28:56.247521Z","steps":["trace[1952607268] 'read index received' (duration: 106.21468ms)","trace[1952607268] 'applied index is now lower than readState.Index' (duration: 255.862µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:28:56.247658Z","caller":"traceutil/trace.go:171","msg":"trace[621813344] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"108.672721ms","start":"2026-05-21T19:28:56.138933Z","end":"2026-05-21T19:28:56.247606Z","steps":["trace[621813344] 'process raft request' (duration: 108.357199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:56.24791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.815017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-21T19:28:56.247971Z","caller":"traceutil/trace.go:171","msg":"trace[78469880] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1109; }","duration":"106.937448ms","start":"2026-05-21T19:28:56.141019Z","end":"2026-05-21T19:28:56.247956Z","steps":["trace[78469880] 'agreement among raft nodes before linearized reading' (duration: 106.641706ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:57.643212Z","caller":"traceutil/trace.go:171","msg":"trace[106972452] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"371.805954ms","start":"2026-05-21T19:28:57.27138Z","end":"2026-05-21T19:28:57.643186Z","steps":["trace[106972452] 'process raft request' (duration: 371.653943ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:57.643407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:57.271349Z","time spent":"371.971296ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:28:57.696962Z","caller":"traceutil/trace.go:171","msg":"trace[2089230681] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"138.561389ms","start":"2026-05-21T19:28:57.558379Z","end":"2026-05-21T19:28:57.69694Z","steps":["trace[2089230681] 'process raft request' (duration: 108.270286ms)","trace[2089230681] 'compare' (duration: 30.187462ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:28:58.481549Z","caller":"traceutil/trace.go:171","msg":"trace[1149765038] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"179.321877ms","start":"2026-05-21T19:28:58.302191Z","end":"2026-05-21T19:28:58.481513Z","steps":["trace[1149765038] 'process raft request' (duration: 89.446712ms)","trace[1149765038] 'compare' (duration: 89.744834ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:28:58.737009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.905954ms","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-21T19:28:58.737121Z","caller":"traceutil/trace.go:171","msg":"trace[1475368161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1117; }","duration":"120.077416ms","start":"2026-05-21T19:28:58.617019Z","end":"2026-05-21T19:28:58.737096Z","steps":["trace[1475368161] 'range keys from in-memory index tree' (duration: 119.725893ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:58.853245Z","caller":"traceutil/trace.go:171","msg":"trace[1833959438] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"110.589933ms","start":"2026-05-21T19:28:58.742626Z","end":"2026-05-21T19:28:58.853216Z","steps":["trace[1833959438] 'process raft request' (duration: 110.403451ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:29:27.411314Z","caller":"traceutil/trace.go:171","msg":"trace[1668070503] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"116.694086ms","start":"2026-05-21T19:29:27.29458Z","end":"2026-05-21T19:29:27.411274Z","steps":["trace[1668070503] 'process raft request' (duration: 116.472364ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:29:29.477253Z","caller":"traceutil/trace.go:171","msg":"trace[866401297] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"364.293015ms","start":"2026-05-21T19:29:29.112896Z","end":"2026-05-21T19:29:29.477189Z","steps":["trace[866401297] 'process raft request' (duration: 325.058054ms)","trace[866401297] 'compare' (duration: 38.848388ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:29:29.477541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:29.112875Z","time spent":"364.519296ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:29:35.329068Z","caller":"traceutil/trace.go:171","msg":"trace[380071407] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"193.88632ms","start":"2026-05-21T19:29:35.135157Z","end":"2026-05-21T19:29:35.329043Z","steps":["trace[380071407] 'process raft request' (duration: 193.732249ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:29:38.558951Z","caller":"traceutil/trace.go:171","msg":"trace[1688642394] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"117.617319ms","start":"2026-05-21T19:29:38.441287Z","end":"2026-05-21T19:29:38.558905Z","steps":["trace[1688642394] 'process raft request' (duration: 117.389357ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:29:40.813554Z","caller":"traceutil/trace.go:171","msg":"trace[364912848] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1313; }","duration":"147.587199ms","start":"2026-05-21T19:29:40.665929Z","end":"2026-05-21T19:29:40.813516Z","steps":["trace[364912848] 'read index received' (duration: 71.015682ms)","trace[364912848] 'applied index is now lower than readState.Index' (duration: 76.569776ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:29:40.813832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.86018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-05-21T19:29:40.813886Z","caller":"traceutil/trace.go:171","msg":"trace[1852587159] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1255; }","duration":"147.995791ms","start":"2026-05-21T19:29:40.665873Z","end":"2026-05-21T19:29:40.813868Z","steps":["trace[1852587159] 'agreement among raft nodes before linearized reading' (duration: 147.78329ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:29:40.813984Z","caller":"traceutil/trace.go:171","msg":"trace[1091042658] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"152.071645ms","start":"2026-05-21T19:29:40.661862Z","end":"2026-05-21T19:29:40.813933Z","steps":["trace[1091042658] 'process raft request' (duration: 75.063915ms)","trace[1091042658] 'compare' (duration: 76.418024ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:29:49.051516Z","caller":"traceutil/trace.go:171","msg":"trace[1432126041] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"129.990966ms","start":"2026-05-21T19:29:48.921505Z","end":"2026-05-21T19:29:49.051496Z","steps":["trace[1432126041] 'process raft request' (duration: 129.877465ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:29:54.839834Z","caller":"traceutil/trace.go:171","msg":"trace[1248477860] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"103.803044ms","start":"2026-05-21T19:29:54.736003Z","end":"2026-05-21T19:29:54.839806Z","steps":["trace[1248477860] 'process raft request' (duration: 103.614701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:29:55.871275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.245526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T19:29:55.871372Z","caller":"traceutil/trace.go:171","msg":"trace[950170047] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1330; }","duration":"108.383408ms","start":"2026-05-21T19:29:55.762968Z","end":"2026-05-21T19:29:55.871352Z","steps":["trace[950170047] 'agreement among raft nodes before linearized reading' (duration: 108.125616ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:29:56.043886Z","caller":"traceutil/trace.go:171","msg":"trace[1977325876] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"112.204328ms","start":"2026-05-21T19:29:55.931651Z","end":"2026-05-21T19:29:56.043855Z","steps":["trace[1977325876] 'process raft request' (duration: 56.645489ms)","trace[1977325876] 'compare' (duration: 55.406058ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:30:00.14965Z","caller":"traceutil/trace.go:171","msg":"trace[35689517] linearizableReadLoop","detail":"{readStateIndex:1412; appliedIndex:1411; }","duration":"318.049118ms","start":"2026-05-21T19:29:59.831557Z","end":"2026-05-21T19:30:00.149606Z","steps":["trace[35689517] 'read index received' (duration: 317.694796ms)","trace[35689517] 'applied index is now lower than readState.Index' (duration: 352.322µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:30:00.149964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.35069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:30:00.149724Z","caller":"traceutil/trace.go:171","msg":"trace[566465923] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"345.186173ms","start":"2026-05-21T19:29:59.804506Z","end":"2026-05-21T19:30:00.149692Z","steps":["trace[566465923] 'process raft request' (duration: 344.81843ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:00.150031Z","caller":"traceutil/trace.go:171","msg":"trace[1427744337] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1349; }","duration":"318.483281ms","start":"2026-05-21T19:29:59.831535Z","end":"2026-05-21T19:30:00.150019Z","steps":["trace[1427744337] 'agreement among raft nodes before linearized reading' (duration: 318.25995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:00.150109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:59.831526Z","time spent":"318.570392ms","remote":"127.0.0.1:35928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":31,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:30:00.150141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:59.804476Z","time spent":"345.569305ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:30:00.280382Z","caller":"traceutil/trace.go:171","msg":"trace[2000137271] linearizableReadLoop","detail":"{readStateIndex:1413; appliedIndex:1412; }","duration":"130.438848ms","start":"2026-05-21T19:30:00.149919Z","end":"2026-05-21T19:30:00.280358Z","steps":["trace[2000137271] 'read index received' (duration: 117.545247ms)","trace[2000137271] 'applied index is now lower than readState.Index' (duration: 12.892151ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:30:00.280529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.49194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:30:00.280563Z","caller":"traceutil/trace.go:171","msg":"trace[97056689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1350; }","duration":"370.582452ms","start":"2026-05-21T19:29:59.909969Z","end":"2026-05-21T19:30:00.280552Z","steps":["trace[97056689] 'agreement among raft nodes before linearized reading' (duration: 370.484461ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:00.280535Z","caller":"traceutil/trace.go:171","msg":"trace[90842733] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"221.932419ms","start":"2026-05-21T19:30:00.058533Z","end":"2026-05-21T19:30:00.280465Z","steps":["trace[90842733] 'process raft request' (duration: 209.067578ms)","trace[90842733] 'compare' (duration: 12.616879ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:30:00.280644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.159475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T19:30:00.280705Z","caller":"traceutil/trace.go:171","msg":"trace[476181107] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1350; }","duration":"362.256276ms","start":"2026-05-21T19:29:59.918438Z","end":"2026-05-21T19:30:00.280695Z","steps":["trace[476181107] 'agreement among raft nodes before linearized reading' (duration: 362.035253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:00.280732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:59.91842Z","time spent":"362.304427ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T19:30:00.280597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:29:59.909945Z","time spent":"370.645272ms","remote":"127.0.0.1:35746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:30:08.410465Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4529669374504712481,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T19:30:08.425734Z","caller":"traceutil/trace.go:171","msg":"trace[1753179633] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"887.24785ms","start":"2026-05-21T19:30:07.538458Z","end":"2026-05-21T19:30:08.425706Z","steps":["trace[1753179633] 'process raft request' (duration: 887.012089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:08.425885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:07.538438Z","time spent":"887.355691ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:30:08.54355Z","caller":"traceutil/trace.go:171","msg":"trace[1365251903] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"229.563408ms","start":"2026-05-21T19:30:08.313959Z","end":"2026-05-21T19:30:08.543522Z","steps":["trace[1365251903] 'process raft request' (duration: 216.418235ms)","trace[1365251903] 'compare' (duration: 12.899861ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:30:08.543803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.43223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:30:08.54344Z","caller":"traceutil/trace.go:171","msg":"trace[1423879253] linearizableReadLoop","detail":"{readStateIndex:1446; appliedIndex:1445; }","duration":"633.084887ms","start":"2026-05-21T19:30:07.910327Z","end":"2026-05-21T19:30:08.543412Z","steps":["trace[1423879253] 'read index received' (duration: 515.447366ms)","trace[1423879253] 'applied index is now lower than readState.Index' (duration: 117.635981ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:30:08.544426Z","caller":"traceutil/trace.go:171","msg":"trace[1084353076] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1381; }","duration":"633.615711ms","start":"2026-05-21T19:30:07.910271Z","end":"2026-05-21T19:30:08.543887Z","steps":["trace[1084353076] 'agreement among raft nodes before linearized reading' (duration: 633.37532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:08.545104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:07.910219Z","time spent":"634.839971ms","remote":"127.0.0.1:35746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:30:08.548549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.335402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-21T19:30:08.548558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.341968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T19:30:08.548596Z","caller":"traceutil/trace.go:171","msg":"trace[57032960] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1382; }","duration":"544.444064ms","start":"2026-05-21T19:30:08.00414Z","end":"2026-05-21T19:30:08.548584Z","steps":["trace[57032960] 'agreement among raft nodes before linearized reading' (duration: 544.269072ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:08.548613Z","caller":"traceutil/trace.go:171","msg":"trace[85435826] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1382; }","duration":"214.445309ms","start":"2026-05-21T19:30:08.334147Z","end":"2026-05-21T19:30:08.548592Z","steps":["trace[85435826] 'agreement among raft nodes before linearized reading' (duration: 214.241737ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:08.548623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:08.004116Z","time spent":"544.499724ms","remote":"127.0.0.1:36248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:30:08.548807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.612709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:30:08.54884Z","caller":"traceutil/trace.go:171","msg":"trace[2065678902] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1382; }","duration":"233.648439ms","start":"2026-05-21T19:30:08.315184Z","end":"2026-05-21T19:30:08.548832Z","steps":["trace[2065678902] 'agreement among raft nodes before linearized reading' (duration: 233.582719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:08.548899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.370779ms","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-21T19:30:08.548948Z","caller":"traceutil/trace.go:171","msg":"trace[626854899] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1382; }","duration":"334.453439ms","start":"2026-05-21T19:30:08.214483Z","end":"2026-05-21T19:30:08.548937Z","steps":["trace[626854899] 'agreement among raft nodes before linearized reading' (duration: 334.323998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:08.548984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:08.214466Z","time spent":"334.504359ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-21T19:30:09.231591Z","caller":"traceutil/trace.go:171","msg":"trace[1757724774] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"139.4502ms","start":"2026-05-21T19:30:09.092109Z","end":"2026-05-21T19:30:09.231559Z","steps":["trace[1757724774] 'process raft request' (duration: 130.112438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:25.93288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.603149ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-21T19:30:25.933038Z","caller":"traceutil/trace.go:171","msg":"trace[1513623811] linearizableReadLoop","detail":"{readStateIndex:1513; appliedIndex:1512; }","duration":"153.50353ms","start":"2026-05-21T19:30:25.779506Z","end":"2026-05-21T19:30:25.93301Z","steps":["trace[1513623811] 'read index received' (duration: 30.740645ms)","trace[1513623811] 'applied index is now lower than readState.Index' (duration: 122.760935ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:30:25.933274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.300695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:ttl-controller\" ","response":"range_response_count:1 size:731"} {"level":"warn","ts":"2026-05-21T19:30:25.933244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.698426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:ttl-after-finished-controller\" ","response":"range_response_count:1 size:791"} {"level":"info","ts":"2026-05-21T19:30:25.933326Z","caller":"traceutil/trace.go:171","msg":"trace[1503781257] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:ttl-controller; range_end:; response_count:1; response_revision:1444; }","duration":"110.384188ms","start":"2026-05-21T19:30:25.822926Z","end":"2026-05-21T19:30:25.93331Z","steps":["trace[1503781257] 'agreement among raft nodes before linearized reading' (duration: 110.253834ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:25.93335Z","caller":"traceutil/trace.go:171","msg":"trace[728196379] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:ttl-after-finished-controller; range_end:; response_count:1; response_revision:1444; }","duration":"153.884032ms","start":"2026-05-21T19:30:25.779442Z","end":"2026-05-21T19:30:25.933326Z","steps":["trace[728196379] 'agreement among raft nodes before linearized reading' (duration: 153.661925ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:31.361547Z","caller":"traceutil/trace.go:171","msg":"trace[680851159] transaction","detail":"{read_only:false; response_revision:1463; number_of_response:1; }","duration":"126.199094ms","start":"2026-05-21T19:30:31.235312Z","end":"2026-05-21T19:30:31.361512Z","steps":["trace[680851159] 'process raft request' (duration: 125.964577ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:31.361464Z","caller":"traceutil/trace.go:171","msg":"trace[826619543] linearizableReadLoop","detail":"{readStateIndex:1533; appliedIndex:1532; }","duration":"123.873808ms","start":"2026-05-21T19:30:31.23756Z","end":"2026-05-21T19:30:31.361433Z","steps":["trace[826619543] 'read index received' (duration: 123.679822ms)","trace[826619543] 'applied index is now lower than readState.Index' (duration: 192.826µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:30:31.366783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.062337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:1914"} {"level":"info","ts":"2026-05-21T19:30:31.366928Z","caller":"traceutil/trace.go:171","msg":"trace[70565165] range","detail":"{range_begin:/registry/services/specs/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:1463; }","duration":"129.331206ms","start":"2026-05-21T19:30:31.237542Z","end":"2026-05-21T19:30:31.366873Z","steps":["trace[70565165] 'agreement among raft nodes before linearized reading' (duration: 124.191778ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:32.182011Z","caller":"traceutil/trace.go:171","msg":"trace[1068231167] linearizableReadLoop","detail":"{readStateIndex:1535; appliedIndex:1534; }","duration":"100.767058ms","start":"2026-05-21T19:30:32.081219Z","end":"2026-05-21T19:30:32.181986Z","steps":["trace[1068231167] 'read index received' (duration: 100.577472ms)","trace[1068231167] 'applied index is now lower than readState.Index' (duration: 188.426µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:30:32.182314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.069278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/\" range_end:\"/registry/daemonsets/kube-system0\" limit:500 ","response":"range_response_count:2 size:17391"} {"level":"info","ts":"2026-05-21T19:30:32.182365Z","caller":"traceutil/trace.go:171","msg":"trace[123540738] range","detail":"{range_begin:/registry/daemonsets/kube-system/; range_end:/registry/daemonsets/kube-system0; response_count:2; response_revision:1465; }","duration":"101.166951ms","start":"2026-05-21T19:30:32.081184Z","end":"2026-05-21T19:30:32.182351Z","steps":["trace[123540738] 'agreement among raft nodes before linearized reading' (duration: 100.931083ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:32.182136Z","caller":"traceutil/trace.go:171","msg":"trace[1006140848] transaction","detail":"{read_only:false; response_revision:1465; number_of_response:1; }","duration":"114.642748ms","start":"2026-05-21T19:30:32.067451Z","end":"2026-05-21T19:30:32.182094Z","steps":["trace[1006140848] 'process raft request' (duration: 114.38761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:32.423722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.279675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-public/system:controller:bootstrap-signer\" ","response":"range_response_count:1 size:741"} {"level":"info","ts":"2026-05-21T19:30:32.423832Z","caller":"traceutil/trace.go:171","msg":"trace[1924335741] range","detail":"{range_begin:/registry/rolebindings/kube-public/system:controller:bootstrap-signer; range_end:; response_count:1; response_revision:1465; }","duration":"151.460572ms","start":"2026-05-21T19:30:32.272349Z","end":"2026-05-21T19:30:32.42381Z","steps":["trace[1924335741] 'range keys from in-memory index tree' (duration: 151.133941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:32.423785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.05009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" limit:500 ","response":"range_response_count:2 size:8921"} {"level":"warn","ts":"2026-05-21T19:30:32.423827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.805014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-public/\" range_end:\"/registry/services/specs/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:30:32.423925Z","caller":"traceutil/trace.go:171","msg":"trace[1960907890] range","detail":"{range_begin:/registry/services/specs/kube-public/; range_end:/registry/services/specs/kube-public0; response_count:0; response_revision:1465; }","duration":"115.161695ms","start":"2026-05-21T19:30:32.308738Z","end":"2026-05-21T19:30:32.4239Z","steps":["trace[1960907890] 'range keys from in-memory index tree' (duration: 114.725511ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:32.423936Z","caller":"traceutil/trace.go:171","msg":"trace[569709468] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:2; response_revision:1465; }","duration":"131.276007ms","start":"2026-05-21T19:30:32.292619Z","end":"2026-05-21T19:30:32.423895Z","steps":["trace[569709468] 'range keys from in-memory index tree' (duration: 130.71491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:33.067174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.184745ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:30:33.067291Z","caller":"traceutil/trace.go:171","msg":"trace[760130454] linearizableReadLoop","detail":"{readStateIndex:1539; appliedIndex:1538; }","duration":"326.615501ms","start":"2026-05-21T19:30:32.740658Z","end":"2026-05-21T19:30:33.067273Z","steps":["trace[760130454] 'read index received' (duration: 54.138387ms)","trace[760130454] 'applied index is now lower than readState.Index' (duration: 272.475684ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:30:33.067467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.146668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:1 size:612"} {"level":"info","ts":"2026-05-21T19:30:33.067442Z","caller":"traceutil/trace.go:171","msg":"trace[624759909] transaction","detail":"{read_only:false; response_revision:1469; number_of_response:1; }","duration":"346.114904ms","start":"2026-05-21T19:30:32.721289Z","end":"2026-05-21T19:30:33.067404Z","steps":["trace[624759909] 'process raft request' (duration: 73.596407ms)","trace[624759909] 'compare' (duration: 272.01743ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:30:33.067519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.016723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:30:33.067538Z","caller":"traceutil/trace.go:171","msg":"trace[2093534610] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1469; }","duration":"158.052354ms","start":"2026-05-21T19:30:32.909481Z","end":"2026-05-21T19:30:33.067534Z","steps":["trace[2093534610] 'agreement among raft nodes before linearized reading' (duration: 158.018093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:33.067623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:32.721271Z","time spent":"346.245707ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:30:33.067651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.977923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/\" range_end:\"/registry/pods/kube-system0\" limit:500 ","response":"range_response_count:10 size:65270"} {"level":"info","ts":"2026-05-21T19:30:33.067487Z","caller":"traceutil/trace.go:171","msg":"trace[1685868751] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:1; response_revision:1469; }","duration":"273.195849ms","start":"2026-05-21T19:30:32.794284Z","end":"2026-05-21T19:30:33.067479Z","steps":["trace[1685868751] 'agreement among raft nodes before linearized reading' (duration: 273.127867ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:33.067732Z","caller":"traceutil/trace.go:171","msg":"trace[736331257] range","detail":"{range_begin:/registry/pods/kube-system/; range_end:/registry/pods/kube-system0; response_count:10; response_revision:1469; }","duration":"327.082016ms","start":"2026-05-21T19:30:32.740623Z","end":"2026-05-21T19:30:33.067705Z","steps":["trace[736331257] 'agreement among raft nodes before linearized reading' (duration: 326.769417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:33.067786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:32.740606Z","time spent":"327.167139ms","remote":"127.0.0.1:35920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":10,"response size":65294,"request content":"key:\"/registry/pods/kube-system/\" range_end:\"/registry/pods/kube-system0\" limit:500 "} {"level":"warn","ts":"2026-05-21T19:30:33.067688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.054026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:1 size:3017"} {"level":"info","ts":"2026-05-21T19:30:33.067889Z","caller":"traceutil/trace.go:171","msg":"trace[542269298] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:1; response_revision:1469; }","duration":"249.259242ms","start":"2026-05-21T19:30:32.8186Z","end":"2026-05-21T19:30:33.067859Z","steps":["trace[542269298] 'agreement among raft nodes before linearized reading' (duration: 249.058636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:33.067515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.569935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:30:33.068118Z","caller":"traceutil/trace.go:171","msg":"trace[394210910] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1469; }","duration":"148.232686ms","start":"2026-05-21T19:30:32.919867Z","end":"2026-05-21T19:30:33.068099Z","steps":["trace[394210910] 'agreement among raft nodes before linearized reading' (duration: 147.554764ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:34.819579Z","caller":"traceutil/trace.go:171","msg":"trace[1217517700] linearizableReadLoop","detail":"{readStateIndex:1543; appliedIndex:1542; }","duration":"159.656642ms","start":"2026-05-21T19:30:34.659898Z","end":"2026-05-21T19:30:34.819555Z","steps":["trace[1217517700] 'read index received' (duration: 158.889217ms)","trace[1217517700] 'applied index is now lower than readState.Index' (duration: 766.215µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:30:34.819699Z","caller":"traceutil/trace.go:171","msg":"trace[505232249] transaction","detail":"{read_only:false; response_revision:1473; number_of_response:1; }","duration":"161.809682ms","start":"2026-05-21T19:30:34.657877Z","end":"2026-05-21T19:30:34.819687Z","steps":["trace[505232249] 'process raft request' (duration: 160.895333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:34.819924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.610638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-8vdts\" ","response":"range_response_count:1 size:4902"} {"level":"info","ts":"2026-05-21T19:30:34.819964Z","caller":"traceutil/trace.go:171","msg":"trace[345436289] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-8vdts; range_end:; response_count:1; response_revision:1473; }","duration":"201.704542ms","start":"2026-05-21T19:30:34.61825Z","end":"2026-05-21T19:30:34.819954Z","steps":["trace[345436289] 'agreement among raft nodes before linearized reading' (duration: 201.558277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:34.8202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.685683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xwlc9\" ","response":"range_response_count:1 size:18525"} {"level":"warn","ts":"2026-05-21T19:30:34.820272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.681842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T19:30:34.820322Z","caller":"traceutil/trace.go:171","msg":"trace[2106609871] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1473; }","duration":"106.756985ms","start":"2026-05-21T19:30:34.713552Z","end":"2026-05-21T19:30:34.820309Z","steps":["trace[2106609871] 'agreement among raft nodes before linearized reading' (duration: 106.60241ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:34.82032Z","caller":"traceutil/trace.go:171","msg":"trace[1604577763] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xwlc9; range_end:; response_count:1; response_revision:1473; }","duration":"149.861518ms","start":"2026-05-21T19:30:34.670432Z","end":"2026-05-21T19:30:34.820293Z","steps":["trace[1604577763] 'agreement among raft nodes before linearized reading' (duration: 149.433795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:35.64196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.069978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-ng7qr\" ","response":"range_response_count:1 size:4482"} {"level":"info","ts":"2026-05-21T19:30:35.642081Z","caller":"traceutil/trace.go:171","msg":"trace[1195089226] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-ng7qr; range_end:; response_count:1; response_revision:1477; }","duration":"123.246484ms","start":"2026-05-21T19:30:35.518814Z","end":"2026-05-21T19:30:35.642061Z","steps":["trace[1195089226] 'range keys from in-memory index tree' (duration: 122.981545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:35.642578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.130422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:86 size:63908"} {"level":"info","ts":"2026-05-21T19:30:35.642682Z","caller":"traceutil/trace.go:171","msg":"trace[1038473842] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:86; response_revision:1477; }","duration":"145.274817ms","start":"2026-05-21T19:30:35.497382Z","end":"2026-05-21T19:30:35.642657Z","steps":["trace[1038473842] 'range keys from in-memory index tree' (duration: 144.504032ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:35.958093Z","caller":"traceutil/trace.go:171","msg":"trace[413033373] linearizableReadLoop","detail":"{readStateIndex:1548; appliedIndex:1547; }","duration":"135.450913ms","start":"2026-05-21T19:30:35.822602Z","end":"2026-05-21T19:30:35.958053Z","steps":["trace[413033373] 'read index received' (duration: 42.532288ms)","trace[413033373] 'applied index is now lower than readState.Index' (duration: 92.917295ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:30:35.958284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.66135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4663"} {"level":"warn","ts":"2026-05-21T19:30:35.958339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.567972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/local-path-storage/kube-root-ca.crt\" ","response":"range_response_count:1 size:1760"} {"level":"info","ts":"2026-05-21T19:30:35.958344Z","caller":"traceutil/trace.go:171","msg":"trace[180228764] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:1477; }","duration":"135.760263ms","start":"2026-05-21T19:30:35.822566Z","end":"2026-05-21T19:30:35.958327Z","steps":["trace[180228764] 'agreement among raft nodes before linearized reading' (duration: 135.594878ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:35.958386Z","caller":"traceutil/trace.go:171","msg":"trace[2000411910] range","detail":"{range_begin:/registry/configmaps/local-path-storage/kube-root-ca.crt; range_end:; response_count:1; response_revision:1477; }","duration":"133.635845ms","start":"2026-05-21T19:30:35.824736Z","end":"2026-05-21T19:30:35.958372Z","steps":["trace[2000411910] 'agreement among raft nodes before linearized reading' (duration: 133.48063ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:36.851164Z","caller":"traceutil/trace.go:171","msg":"trace[2132204731] linearizableReadLoop","detail":"{readStateIndex:1549; appliedIndex:1548; }","duration":"144.172687ms","start":"2026-05-21T19:30:36.706968Z","end":"2026-05-21T19:30:36.851141Z","steps":["trace[2132204731] 'read index received' (duration: 144.010792ms)","trace[2132204731] 'applied index is now lower than readState.Index' (duration: 160.215µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:30:36.85131Z","caller":"traceutil/trace.go:171","msg":"trace[2037778450] transaction","detail":"{read_only:false; response_revision:1478; number_of_response:1; }","duration":"184.065422ms","start":"2026-05-21T19:30:36.667196Z","end":"2026-05-21T19:30:36.851261Z","steps":["trace[2037778450] 'process raft request' (duration: 183.733342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:36.851392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.409015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system::leader-locking-kube-scheduler\" ","response":"range_response_count:1 size:808"} {"level":"info","ts":"2026-05-21T19:30:36.851485Z","caller":"traceutil/trace.go:171","msg":"trace[1289742732] range","detail":"{range_begin:/registry/rolebindings/kube-system/system::leader-locking-kube-scheduler; range_end:; response_count:1; response_revision:1478; }","duration":"144.531979ms","start":"2026-05-21T19:30:36.706927Z","end":"2026-05-21T19:30:36.851459Z","steps":["trace[1289742732] 'agreement among raft nodes before linearized reading' (duration: 144.333322ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:37.203617Z","caller":"traceutil/trace.go:171","msg":"trace[1717150844] linearizableReadLoop","detail":"{readStateIndex:1550; appliedIndex:1549; }","duration":"352.365163ms","start":"2026-05-21T19:30:36.851238Z","end":"2026-05-21T19:30:37.203603Z","steps":["trace[1717150844] 'read index received' (duration: 352.212818ms)","trace[1717150844] 'applied index is now lower than readState.Index' (duration: 150.315µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:30:37.203793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.611495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T19:30:37.203672Z","caller":"traceutil/trace.go:171","msg":"trace[480267232] transaction","detail":"{read_only:false; response_revision:1479; number_of_response:1; }","duration":"377.561823ms","start":"2026-05-21T19:30:36.826075Z","end":"2026-05-21T19:30:37.203637Z","steps":["trace[480267232] 'process raft request' (duration: 377.425149ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:37.203847Z","caller":"traceutil/trace.go:171","msg":"trace[478285103] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1479; }","duration":"366.715219ms","start":"2026-05-21T19:30:36.837117Z","end":"2026-05-21T19:30:37.203832Z","steps":["trace[478285103] 'agreement among raft nodes before linearized reading' (duration: 366.561394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:37.20389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:36.837094Z","time spent":"366.78824ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T19:30:37.203976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.125865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system::leader-locking-kube-scheduler\" ","response":"range_response_count:1 size:808"} {"level":"info","ts":"2026-05-21T19:30:37.204023Z","caller":"traceutil/trace.go:171","msg":"trace[86035475] range","detail":"{range_begin:/registry/rolebindings/kube-system/system::leader-locking-kube-scheduler; range_end:; response_count:1; response_revision:1479; }","duration":"272.204177ms","start":"2026-05-21T19:30:36.931809Z","end":"2026-05-21T19:30:37.204013Z","steps":["trace[86035475] 'agreement among raft nodes before linearized reading' (duration: 272.132725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:37.204003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:36.826042Z","time spent":"377.852812ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:30:37.204074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.031345ms","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-21T19:30:37.20412Z","caller":"traceutil/trace.go:171","msg":"trace[379159006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1479; }","duration":"123.101137ms","start":"2026-05-21T19:30:37.081007Z","end":"2026-05-21T19:30:37.204109Z","steps":["trace[379159006] 'agreement among raft nodes before linearized reading' (duration: 122.954443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:37.203827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.521491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system:controller:bootstrap-signer\" ","response":"range_response_count:1 size:741"} {"level":"info","ts":"2026-05-21T19:30:37.204267Z","caller":"traceutil/trace.go:171","msg":"trace[1985752308] range","detail":"{range_begin:/registry/rolebindings/kube-system/system:controller:bootstrap-signer; range_end:; response_count:1; response_revision:1479; }","duration":"491.992294ms","start":"2026-05-21T19:30:36.712249Z","end":"2026-05-21T19:30:37.204241Z","steps":["trace[1985752308] 'agreement among raft nodes before linearized reading' (duration: 491.489289ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:37.204303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:36.712228Z","time spent":"492.065127ms","remote":"127.0.0.1:36070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":765,"request content":"key:\"/registry/rolebindings/kube-system/system:controller:bootstrap-signer\" "} {"level":"info","ts":"2026-05-21T19:30:37.502963Z","caller":"traceutil/trace.go:171","msg":"trace[525804146] transaction","detail":"{read_only:false; response_revision:1480; number_of_response:1; }","duration":"294.513041ms","start":"2026-05-21T19:30:37.208421Z","end":"2026-05-21T19:30:37.502934Z","steps":["trace[525804146] 'process raft request' (duration: 195.04135ms)","trace[525804146] 'compare' (duration: 99.306526ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:30:37.58028Z","caller":"traceutil/trace.go:171","msg":"trace[1469638223] linearizableReadLoop","detail":"{readStateIndex:1552; appliedIndex:1550; }","duration":"302.39807ms","start":"2026-05-21T19:30:37.277861Z","end":"2026-05-21T19:30:37.580259Z","steps":["trace[1469638223] 'read index received' (duration: 125.610066ms)","trace[1469638223] 'applied index is now lower than readState.Index' (duration: 176.786984ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:30:37.580386Z","caller":"traceutil/trace.go:171","msg":"trace[1738187288] transaction","detail":"{read_only:false; response_revision:1481; number_of_response:1; }","duration":"371.824953ms","start":"2026-05-21T19:30:37.208518Z","end":"2026-05-21T19:30:37.580343Z","steps":["trace[1738187288] 'process raft request' (duration: 371.635828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:37.580462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.755545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system:controller:cloud-provider\" ","response":"range_response_count:1 size:733"} {"level":"info","ts":"2026-05-21T19:30:37.580509Z","caller":"traceutil/trace.go:171","msg":"trace[683233851] range","detail":"{range_begin:/registry/rolebindings/kube-system/system:controller:cloud-provider; range_end:; response_count:1; response_revision:1481; }","duration":"292.856678ms","start":"2026-05-21T19:30:37.287634Z","end":"2026-05-21T19:30:37.580491Z","steps":["trace[683233851] 'agreement among raft nodes before linearized reading' (duration: 292.751415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:37.580493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.610676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system:controller:bootstrap-signer\" ","response":"range_response_count:1 size:741"} {"level":"info","ts":"2026-05-21T19:30:37.580594Z","caller":"traceutil/trace.go:171","msg":"trace[189187138] range","detail":"{range_begin:/registry/rolebindings/kube-system/system:controller:bootstrap-signer; range_end:; response_count:1; response_revision:1481; }","duration":"302.74982ms","start":"2026-05-21T19:30:37.277818Z","end":"2026-05-21T19:30:37.580567Z","steps":["trace[189187138] 'agreement among raft nodes before linearized reading' (duration: 302.562105ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:37.580597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:37.208501Z","time spent":"371.989828ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:30:37.580652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:37.277796Z","time spent":"302.847484ms","remote":"127.0.0.1:36070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":765,"request content":"key:\"/registry/rolebindings/kube-system/system:controller:bootstrap-signer\" "} {"level":"warn","ts":"2026-05-21T19:30:37.58068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.670438ms","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-21T19:30:37.580765Z","caller":"traceutil/trace.go:171","msg":"trace[1181234669] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1481; }","duration":"143.805252ms","start":"2026-05-21T19:30:37.436941Z","end":"2026-05-21T19:30:37.580746Z","steps":["trace[1181234669] 'agreement among raft nodes before linearized reading' (duration: 143.564114ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:37.707116Z","caller":"traceutil/trace.go:171","msg":"trace[989402934] linearizableReadLoop","detail":"{readStateIndex:1553; appliedIndex:1552; }","duration":"121.016421ms","start":"2026-05-21T19:30:37.586069Z","end":"2026-05-21T19:30:37.707085Z","steps":["trace[989402934] 'read index received' (duration: 48.425359ms)","trace[989402934] 'applied index is now lower than readState.Index' (duration: 72.589632ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:30:37.707205Z","caller":"traceutil/trace.go:171","msg":"trace[906881699] transaction","detail":"{read_only:false; response_revision:1482; number_of_response:1; }","duration":"121.179527ms","start":"2026-05-21T19:30:37.585979Z","end":"2026-05-21T19:30:37.707159Z","steps":["trace[906881699] 'process raft request' (duration: 48.392228ms)","trace[906881699] 'compare' (duration: 72.580242ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:30:37.707321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.222798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system:controller:bootstrap-signer\" ","response":"range_response_count:1 size:741"} {"level":"info","ts":"2026-05-21T19:30:37.707372Z","caller":"traceutil/trace.go:171","msg":"trace[1216047404] range","detail":"{range_begin:/registry/rolebindings/kube-system/system:controller:bootstrap-signer; range_end:; response_count:1; response_revision:1482; }","duration":"121.326361ms","start":"2026-05-21T19:30:37.586029Z","end":"2026-05-21T19:30:37.707355Z","steps":["trace[1216047404] 'agreement among raft nodes before linearized reading' (duration: 121.205477ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:30:37.716966Z","caller":"traceutil/trace.go:171","msg":"trace[740342989] transaction","detail":"{read_only:false; response_revision:1483; number_of_response:1; }","duration":"130.82374ms","start":"2026-05-21T19:30:37.586117Z","end":"2026-05-21T19:30:37.716941Z","steps":["trace[740342989] 'process raft request' (duration: 130.567572ms)"],"step_count":1}