{"level":"warn","ts":"2026-04-28T13:49:53.089266Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T13:49:53.089375Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.132:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.132:2380","--initial-cluster=instance=https://199.204.45.132:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.132:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.132:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-28T13:49:53.089453Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T13:49:53.089462Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-04-28T13:49:53.08948Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-28T13:49:53.090164Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"]} {"level":"info","ts":"2026-04-28T13:49:53.090246Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.132:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-28T13:49:53.09725Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.781288ms"} {"level":"info","ts":"2026-04-28T13:49:53.109116Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"56c01ea8fdc90390","cluster-id":"c930fe821aab8609"} {"level":"info","ts":"2026-04-28T13:49:53.109218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T13:49:53.109245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 0"} {"level":"info","ts":"2026-04-28T13:49:53.109265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 56c01ea8fdc90390 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T13:49:53.109281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 1"} {"level":"info","ts":"2026-04-28T13:49:53.109321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"warn","ts":"2026-04-28T13:49:53.118327Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T13:49:53.119666Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T13:49:53.121417Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-28T13:49:53.12383Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"56c01ea8fdc90390","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T13:49:53.124342Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T13:49:53.124532Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T13:49:53.124575Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T13:49:53.124708Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"56c01ea8fdc90390","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T13:49:53.127011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"info","ts":"2026-04-28T13:49:53.127166Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","added-peer-id":"56c01ea8fdc90390","added-peer-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-04-28T13:49:53.131007Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-28T13:49:53.131131Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-04-28T13:49:53.131159Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-04-28T13:49:53.131318Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"56c01ea8fdc90390","initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T13:49:53.131361Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T13:49:53.909905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T13:49:53.909966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T13:49:53.909989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgPreVoteResp from 56c01ea8fdc90390 at term 1"} {"level":"info","ts":"2026-04-28T13:49:53.910009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became candidate at term 2"} {"level":"info","ts":"2026-04-28T13:49:53.91002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgVoteResp from 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-04-28T13:49:53.910037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became leader at term 2"} {"level":"info","ts":"2026-04-28T13:49:53.910054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 56c01ea8fdc90390 elected leader 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-04-28T13:49:53.911122Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T13:49:53.911953Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T13:49:53.911961Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"56c01ea8fdc90390","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.132:2379]}","request-path":"/0/members/56c01ea8fdc90390/attributes","cluster-id":"c930fe821aab8609","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T13:49:53.912236Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T13:49:53.912263Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T13:49:53.912381Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T13:49:53.91242Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T13:49:53.912534Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T13:49:53.912646Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T13:49:53.915773Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T13:49:53.918346Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.132:2379"} {"level":"info","ts":"2026-04-28T13:50:21.756716Z","caller":"traceutil/trace.go:171","msg":"trace[228005059] linearizableReadLoop","detail":"{readStateIndex:479; appliedIndex:478; }","duration":"198.211024ms","start":"2026-04-28T13:50:21.558471Z","end":"2026-04-28T13:50:21.756682Z","steps":["trace[228005059] 'read index received' (duration: 113.82191ms)","trace[228005059] 'applied index is now lower than readState.Index' (duration: 84.388164ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:50:21.75677Z","caller":"traceutil/trace.go:171","msg":"trace[1621047542] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"207.731608ms","start":"2026-04-28T13:50:21.549002Z","end":"2026-04-28T13:50:21.756734Z","steps":["trace[1621047542] 'process raft request' (duration: 123.220292ms)","trace[1621047542] 'compare' (duration: 84.322703ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:50:21.757369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.802168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-04-28T13:50:21.757519Z","caller":"traceutil/trace.go:171","msg":"trace[186073578] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:458; }","duration":"210.074373ms","start":"2026-04-28T13:50:21.547423Z","end":"2026-04-28T13:50:21.757497Z","steps":["trace[186073578] 'agreement among raft nodes before linearized reading' (duration: 209.578474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:21.757599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.099349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14463"} {"level":"info","ts":"2026-04-28T13:50:21.757671Z","caller":"traceutil/trace.go:171","msg":"trace[1973001715] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:458; }","duration":"204.19644ms","start":"2026-04-28T13:50:21.553459Z","end":"2026-04-28T13:50:21.757655Z","steps":["trace[1973001715] 'agreement among raft nodes before linearized reading' (duration: 203.956746ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:50:21.761988Z","caller":"traceutil/trace.go:171","msg":"trace[1141796156] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"196.883488ms","start":"2026-04-28T13:50:21.565084Z","end":"2026-04-28T13:50:21.761967Z","steps":["trace[1141796156] 'process raft request' (duration: 196.695735ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:50:21.762296Z","caller":"traceutil/trace.go:171","msg":"trace[1730199343] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"187.58538ms","start":"2026-04-28T13:50:21.574696Z","end":"2026-04-28T13:50:21.762281Z","steps":["trace[1730199343] 'process raft request' (duration: 187.178452ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:50:21.762598Z","caller":"traceutil/trace.go:171","msg":"trace[430762040] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"182.85198ms","start":"2026-04-28T13:50:21.579731Z","end":"2026-04-28T13:50:21.762583Z","steps":["trace[430762040] 'process raft request' (duration: 182.37815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:22.052497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.10854ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:50:22.052614Z","caller":"traceutil/trace.go:171","msg":"trace[697743872] linearizableReadLoop","detail":"{readStateIndex:484; appliedIndex:483; }","duration":"214.789855ms","start":"2026-04-28T13:50:21.837807Z","end":"2026-04-28T13:50:22.052597Z","steps":["trace[697743872] 'read index received' (duration: 37.90678ms)","trace[697743872] 'applied index is now lower than readState.Index' (duration: 176.881845ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:50:22.052844Z","caller":"traceutil/trace.go:171","msg":"trace[411125747] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"275.298069ms","start":"2026-04-28T13:50:21.777524Z","end":"2026-04-28T13:50:22.052822Z","steps":["trace[411125747] 'process raft request' (duration: 98.20725ms)","trace[411125747] 'compare' (duration: 175.932587ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:50:22.053076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.117426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4671"} {"level":"warn","ts":"2026-04-28T13:50:22.053091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.075093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-n8fbs\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-04-28T13:50:22.053272Z","caller":"traceutil/trace.go:171","msg":"trace[785194994] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-n8fbs; range_end:; response_count:1; response_revision:463; }","duration":"277.240286ms","start":"2026-04-28T13:50:21.77597Z","end":"2026-04-28T13:50:22.05321Z","steps":["trace[785194994] 'agreement among raft nodes before linearized reading' (duration: 276.849069ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:50:22.053167Z","caller":"traceutil/trace.go:171","msg":"trace[811646112] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:463; }","duration":"208.254728ms","start":"2026-04-28T13:50:21.844896Z","end":"2026-04-28T13:50:22.053151Z","steps":["trace[811646112] 'agreement among raft nodes before linearized reading' (duration: 208.052105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:22.053434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.093926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329948"} {"level":"info","ts":"2026-04-28T13:50:22.053525Z","caller":"traceutil/trace.go:171","msg":"trace[1964442942] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:463; }","duration":"220.237849ms","start":"2026-04-28T13:50:21.833263Z","end":"2026-04-28T13:50:22.053501Z","steps":["trace[1964442942] 'agreement among raft nodes before linearized reading' (duration: 219.873892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:22.625699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.118234ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:50:22.625782Z","caller":"traceutil/trace.go:171","msg":"trace[147073394] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"561.409855ms","start":"2026-04-28T13:50:22.064362Z","end":"2026-04-28T13:50:22.625772Z","steps":["trace[147073394] 'process raft request' (duration: 152.163139ms)","trace[147073394] 'compare' (duration: 409.023012ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:50:22.625824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:22.06434Z","time spent":"561.468666ms","remote":"127.0.0.1:35934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4668,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:50:22.757023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.248756ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-28T13:50:22.757094Z","caller":"traceutil/trace.go:171","msg":"trace[2002378254] linearizableReadLoop","detail":"{readStateIndex:486; appliedIndex:484; }","duration":"598.152232ms","start":"2026-04-28T13:50:22.158931Z","end":"2026-04-28T13:50:22.757083Z","steps":["trace[2002378254] 'read index received' (duration: 57.589278ms)","trace[2002378254] 'applied index is now lower than readState.Index' (duration: 540.562034ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:50:22.757236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.059404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-04-28T13:50:22.757306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.379007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329948"} {"level":"info","ts":"2026-04-28T13:50:22.757324Z","caller":"traceutil/trace.go:171","msg":"trace[181923169] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:464; }","duration":"501.173906ms","start":"2026-04-28T13:50:22.256117Z","end":"2026-04-28T13:50:22.757291Z","steps":["trace[181923169] 'agreement among raft nodes before linearized reading' (duration: 501.059733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:22.757369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:22.256091Z","time spent":"501.269408ms","remote":"127.0.0.1:36018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-28T13:50:22.757334Z","caller":"traceutil/trace.go:171","msg":"trace[536336550] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:464; }","duration":"598.435097ms","start":"2026-04-28T13:50:22.15889Z","end":"2026-04-28T13:50:22.757325Z","steps":["trace[536336550] 'agreement among raft nodes before linearized reading' (duration: 598.253874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:22.757495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:22.15887Z","time spent":"598.616571ms","remote":"127.0.0.1:35832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329971,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-04-28T13:50:22.936118Z","caller":"traceutil/trace.go:171","msg":"trace[568157534] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"175.224052ms","start":"2026-04-28T13:50:22.760875Z","end":"2026-04-28T13:50:22.936099Z","steps":["trace[568157534] 'process raft request' (duration: 175.09748ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:50:24.916603Z","caller":"traceutil/trace.go:171","msg":"trace[65502578] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"289.647345ms","start":"2026-04-28T13:50:24.626921Z","end":"2026-04-28T13:50:24.916569Z","steps":["trace[65502578] 'process raft request' (duration: 289.531603ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:50:24.970557Z","caller":"traceutil/trace.go:171","msg":"trace[807134458] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"305.181063ms","start":"2026-04-28T13:50:24.665352Z","end":"2026-04-28T13:50:24.970533Z","steps":["trace[807134458] 'process raft request' (duration: 304.96926ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:50:24.970871Z","caller":"traceutil/trace.go:171","msg":"trace[1182670636] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"330.601283ms","start":"2026-04-28T13:50:24.639852Z","end":"2026-04-28T13:50:24.970453Z","steps":["trace[1182670636] 'process raft request' (duration: 330.277706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:24.971538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:24.665329Z","time spent":"305.476939ms","remote":"127.0.0.1:35782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151212,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:50:24.971337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:24.639821Z","time spent":"331.475219ms","remote":"127.0.0.1:35782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98698,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:50:25.144403Z","caller":"traceutil/trace.go:171","msg":"trace[77177012] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"103.719906ms","start":"2026-04-28T13:50:25.040622Z","end":"2026-04-28T13:50:25.144342Z","steps":["trace[77177012] 'process raft request' (duration: 103.406571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:25.489259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.711349ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:50:25.48937Z","caller":"traceutil/trace.go:171","msg":"trace[1883813714] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"379.763119ms","start":"2026-04-28T13:50:25.109589Z","end":"2026-04-28T13:50:25.489352Z","steps":["trace[1883813714] 'process raft request' (duration: 147.873937ms)","trace[1883813714] 'compare' (duration: 231.582397ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:50:25.489427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:25.109558Z","time spent":"379.84034ms","remote":"127.0.0.1:35782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74745,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:50:25.491376Z","caller":"traceutil/trace.go:171","msg":"trace[2121680424] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"313.113066ms","start":"2026-04-28T13:50:25.178239Z","end":"2026-04-28T13:50:25.491352Z","steps":["trace[2121680424] 'process raft request' (duration: 312.896062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:25.491519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:25.178217Z","time spent":"313.233289ms","remote":"127.0.0.1:35782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:50:25.492182Z","caller":"traceutil/trace.go:171","msg":"trace[383160763] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"284.478806ms","start":"2026-04-28T13:50:25.207689Z","end":"2026-04-28T13:50:25.492168Z","steps":["trace[383160763] 'process raft request' (duration: 283.935736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:27.779407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.664063226s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:50:27.779768Z","caller":"traceutil/trace.go:171","msg":"trace[1200676358] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"1.652134427s","start":"2026-04-28T13:50:26.127614Z","end":"2026-04-28T13:50:27.779748Z","steps":["trace[1200676358] 'process raft request' (duration: 1.651958533s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:27.779847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:26.127596Z","time spent":"1.652213849s","remote":"127.0.0.1:35782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208470,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:50:27.779725Z","caller":"traceutil/trace.go:171","msg":"trace[1851791189] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:573; }","duration":"1.686010169s","start":"2026-04-28T13:50:26.093673Z","end":"2026-04-28T13:50:27.779683Z","steps":["trace[1851791189] 'read index received' (duration: 20.840642ms)","trace[1851791189] 'applied index is now lower than readState.Index' (duration: 1.665164707s)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:50:27.781183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.973949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.132\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-28T13:50:27.781292Z","caller":"traceutil/trace.go:171","msg":"trace[1658261987] range","detail":"{range_begin:/registry/masterleases/199.204.45.132; range_end:; response_count:1; response_revision:550; }","duration":"810.117481ms","start":"2026-04-28T13:50:26.971131Z","end":"2026-04-28T13:50:27.781249Z","steps":["trace[1658261987] 'agreement among raft nodes before linearized reading' (duration: 808.848167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:27.781348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:26.9711Z","time spent":"810.234633ms","remote":"127.0.0.1:35754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.132\" "} {"level":"warn","ts":"2026-04-28T13:50:27.781679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.465012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T13:50:27.781714Z","caller":"traceutil/trace.go:171","msg":"trace[1530866566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:550; }","duration":"196.559954ms","start":"2026-04-28T13:50:27.585137Z","end":"2026-04-28T13:50:27.781697Z","steps":["trace[1530866566] 'agreement among raft nodes before linearized reading' (duration: 196.467951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:27.781848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.826329ms","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-04-28T13:50:27.781879Z","caller":"traceutil/trace.go:171","msg":"trace[1894562939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:550; }","duration":"210.90139ms","start":"2026-04-28T13:50:27.570969Z","end":"2026-04-28T13:50:27.78187Z","steps":["trace[1894562939] 'agreement among raft nodes before linearized reading' (duration: 210.823089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:27.781969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.55633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:50:27.781999Z","caller":"traceutil/trace.go:171","msg":"trace[916671763] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:550; }","duration":"294.60028ms","start":"2026-04-28T13:50:27.487382Z","end":"2026-04-28T13:50:27.781983Z","steps":["trace[916671763] 'agreement among raft nodes before linearized reading' (duration: 294.564619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:27.782313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.931652ms","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-04-28T13:50:27.78235Z","caller":"traceutil/trace.go:171","msg":"trace[550133196] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:550; }","duration":"711.995884ms","start":"2026-04-28T13:50:27.070335Z","end":"2026-04-28T13:50:27.782331Z","steps":["trace[550133196] 'agreement among raft nodes before linearized reading' (duration: 711.900872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:27.782381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:27.070312Z","time spent":"712.061615ms","remote":"127.0.0.1:36018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T13:50:27.782594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.686327685s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/tcproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31145"} {"level":"info","ts":"2026-04-28T13:50:27.78264Z","caller":"traceutil/trace.go:171","msg":"trace[975316104] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/tcproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:550; }","duration":"1.688958186s","start":"2026-04-28T13:50:26.093654Z","end":"2026-04-28T13:50:27.782613Z","steps":["trace[975316104] 'agreement among raft nodes before linearized reading' (duration: 1.686145522s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:27.782671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:26.093645Z","time spent":"1.689015387s","remote":"127.0.0.1:35782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":31168,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/tcproutes.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-04-28T13:50:27.782688Z","caller":"traceutil/trace.go:171","msg":"trace[428624602] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"1.690389393s","start":"2026-04-28T13:50:26.092233Z","end":"2026-04-28T13:50:27.782623Z","steps":["trace[428624602] 'process raft request' (duration: 22.379971ms)","trace[428624602] 'compare' (duration: 1.663246111s)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:50:27.782885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:26.092215Z","time spent":"1.690584656s","remote":"127.0.0.1:36018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:50:28.288273Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878714136844681,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T13:50:28.973459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.509623ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-04-28T13:50:28.973778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:27.787579Z","time spent":"1.18618434s","remote":"127.0.0.1:35754","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-28T13:50:28.973936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.185865963s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:50:28.973842Z","caller":"traceutil/trace.go:171","msg":"trace[1648078680] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:575; }","duration":"1.185760982s","start":"2026-04-28T13:50:27.788063Z","end":"2026-04-28T13:50:28.973824Z","steps":["trace[1648078680] 'read index received' (duration: 790.906462ms)","trace[1648078680] 'applied index is now lower than readState.Index' (duration: 394.85373ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:50:28.973966Z","caller":"traceutil/trace.go:171","msg":"trace[384889017] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"1.18309605s","start":"2026-04-28T13:50:27.790816Z","end":"2026-04-28T13:50:28.973912Z","steps":["trace[384889017] 'process raft request' (duration: 1.182812005s)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:50:28.974006Z","caller":"traceutil/trace.go:171","msg":"trace[1677608609] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"1.182466848s","start":"2026-04-28T13:50:27.791531Z","end":"2026-04-28T13:50:28.973998Z","steps":["trace[1677608609] 'process raft request' (duration: 1.182224153s)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:50:28.973971Z","caller":"traceutil/trace.go:171","msg":"trace[520626890] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"1.185941685s","start":"2026-04-28T13:50:27.788017Z","end":"2026-04-28T13:50:28.973959Z","steps":["trace[520626890] 'agreement among raft nodes before linearized reading' (duration: 1.185860834s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:28.974128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:27.790792Z","time spent":"1.183225453s","remote":"127.0.0.1:36018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:50:28.97413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:27.791513Z","time spent":"1.182532059s","remote":"127.0.0.1:36018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:50:28.974183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:27.787991Z","time spent":"1.186138809s","remote":"127.0.0.1:35726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T13:50:28.974246Z","caller":"traceutil/trace.go:171","msg":"trace[1007238511] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"1.182703532s","start":"2026-04-28T13:50:27.791531Z","end":"2026-04-28T13:50:28.974235Z","steps":["trace[1007238511] 'process raft request' (duration: 1.182260484s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:28.974352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:27.791514Z","time spent":"1.182774583s","remote":"127.0.0.1:36018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:50:28.974496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.176285259s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/tlsroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:65214"} {"level":"info","ts":"2026-04-28T13:50:28.97455Z","caller":"traceutil/trace.go:171","msg":"trace[928969655] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/tlsroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:553; }","duration":"1.176356931s","start":"2026-04-28T13:50:27.798175Z","end":"2026-04-28T13:50:28.974532Z","steps":["trace[928969655] 'agreement among raft nodes before linearized reading' (duration: 1.176192078s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:28.974608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:27.798165Z","time spent":"1.176431143s","remote":"127.0.0.1:35782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":65237,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/tlsroutes.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-04-28T13:50:36.003713Z","caller":"traceutil/trace.go:171","msg":"trace[900428440] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"156.559784ms","start":"2026-04-28T13:50:35.847117Z","end":"2026-04-28T13:50:36.003677Z","steps":["trace[900428440] 'read index received' (duration: 156.297299ms)","trace[900428440] 'applied index is now lower than readState.Index' (duration: 260.895µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:50:36.003756Z","caller":"traceutil/trace.go:171","msg":"trace[503970031] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"160.843406ms","start":"2026-04-28T13:50:35.842878Z","end":"2026-04-28T13:50:36.003722Z","steps":["trace[503970031] 'process raft request' (duration: 160.585681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:36.004028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.882139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7zsgx\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-28T13:50:36.004115Z","caller":"traceutil/trace.go:171","msg":"trace[2074212455] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7zsgx; range_end:; response_count:1; response_revision:594; }","duration":"157.023791ms","start":"2026-04-28T13:50:35.847068Z","end":"2026-04-28T13:50:36.004092Z","steps":["trace[2074212455] 'agreement among raft nodes before linearized reading' (duration: 156.747506ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:50:36.015137Z","caller":"traceutil/trace.go:171","msg":"trace[1369863245] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"125.435825ms","start":"2026-04-28T13:50:35.88968Z","end":"2026-04-28T13:50:36.015115Z","steps":["trace[1369863245] 'process raft request' (duration: 125.298682ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:50:36.216068Z","caller":"traceutil/trace.go:171","msg":"trace[2073891422] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"194.286178ms","start":"2026-04-28T13:50:36.021703Z","end":"2026-04-28T13:50:36.215989Z","steps":["trace[2073891422] 'process raft request' (duration: 194.125595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:37.059326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.903699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:50:37.059394Z","caller":"traceutil/trace.go:171","msg":"trace[203520968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"569.99271ms","start":"2026-04-28T13:50:36.489374Z","end":"2026-04-28T13:50:37.059367Z","steps":["trace[203520968] 'range keys from in-memory index tree' (duration: 569.800497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:37.059414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:36.489354Z","time spent":"570.054822ms","remote":"127.0.0.1:35726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T13:50:39.225014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.219019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-04-28T13:50:39.224881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.396271ms","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-04-28T13:50:39.225092Z","caller":"traceutil/trace.go:171","msg":"trace[537747798] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:628; }","duration":"101.341622ms","start":"2026-04-28T13:50:39.123733Z","end":"2026-04-28T13:50:39.225075Z","steps":["trace[537747798] 'range keys from in-memory index tree' (duration: 101.076976ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:50:39.225099Z","caller":"traceutil/trace.go:171","msg":"trace[1190292413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:628; }","duration":"102.663167ms","start":"2026-04-28T13:50:39.122418Z","end":"2026-04-28T13:50:39.225081Z","steps":["trace[1190292413] 'range keys from in-memory index tree' (duration: 102.206247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:39.225136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.552535ms","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-04-28T13:50:39.225173Z","caller":"traceutil/trace.go:171","msg":"trace[402325591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"102.753208ms","start":"2026-04-28T13:50:39.122407Z","end":"2026-04-28T13:50:39.22516Z","steps":["trace[402325591] 'range keys from in-memory index tree' (duration: 102.3547ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:50:39.229674Z","caller":"traceutil/trace.go:171","msg":"trace[1631036824] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"105.563663ms","start":"2026-04-28T13:50:39.124088Z","end":"2026-04-28T13:50:39.229651Z","steps":["trace[1631036824] 'process raft request' (duration: 105.42998ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:50:41.429768Z","caller":"traceutil/trace.go:171","msg":"trace[386473118] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:686; }","duration":"108.10126ms","start":"2026-04-28T13:50:41.321642Z","end":"2026-04-28T13:50:41.429744Z","steps":["trace[386473118] 'read index received' (duration: 59.089357ms)","trace[386473118] 'applied index is now lower than readState.Index' (duration: 49.010793ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:50:41.429916Z","caller":"traceutil/trace.go:171","msg":"trace[1930366132] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"189.937834ms","start":"2026-04-28T13:50:41.239941Z","end":"2026-04-28T13:50:41.429879Z","steps":["trace[1930366132] 'process raft request' (duration: 140.831749ms)","trace[1930366132] 'compare' (duration: 48.774379ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:50:41.430097Z","caller":"traceutil/trace.go:171","msg":"trace[1330824051] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"189.722931ms","start":"2026-04-28T13:50:41.240353Z","end":"2026-04-28T13:50:41.430076Z","steps":["trace[1330824051] 'process raft request' (duration: 189.335834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:41.430052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.392226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-04-28T13:50:41.431034Z","caller":"traceutil/trace.go:171","msg":"trace[912319480] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:660; }","duration":"109.401465ms","start":"2026-04-28T13:50:41.321601Z","end":"2026-04-28T13:50:41.431002Z","steps":["trace[912319480] 'agreement among raft nodes before linearized reading' (duration: 108.250003ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:50:43.895709Z","caller":"traceutil/trace.go:171","msg":"trace[344234241] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:710; }","duration":"184.623972ms","start":"2026-04-28T13:50:43.711063Z","end":"2026-04-28T13:50:43.895687Z","steps":["trace[344234241] 'read index received' (duration: 184.534231ms)","trace[344234241] 'applied index is now lower than readState.Index' (duration: 88.721µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:50:43.895849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.768856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:50:43.895895Z","caller":"traceutil/trace.go:171","msg":"trace[407157313] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:683; }","duration":"184.863358ms","start":"2026-04-28T13:50:43.711016Z","end":"2026-04-28T13:50:43.895879Z","steps":["trace[407157313] 'agreement among raft nodes before linearized reading' (duration: 184.782997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:43.895905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.842647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:50:43.896063Z","caller":"traceutil/trace.go:171","msg":"trace[356938857] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:683; }","duration":"184.913889ms","start":"2026-04-28T13:50:43.711016Z","end":"2026-04-28T13:50:43.89593Z","steps":["trace[356938857] 'agreement among raft nodes before linearized reading' (duration: 184.853928ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:50:43.896196Z","caller":"traceutil/trace.go:171","msg":"trace[1586634899] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"209.745077ms","start":"2026-04-28T13:50:43.68644Z","end":"2026-04-28T13:50:43.896185Z","steps":["trace[1586634899] 'process raft request' (duration: 209.118584ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:50:44.078305Z","caller":"traceutil/trace.go:171","msg":"trace[125575844] linearizableReadLoop","detail":"{readStateIndex:713; appliedIndex:711; }","duration":"182.504134ms","start":"2026-04-28T13:50:43.895775Z","end":"2026-04-28T13:50:44.07828Z","steps":["trace[125575844] 'read index received' (duration: 182.281539ms)","trace[125575844] 'applied index is now lower than readState.Index' (duration: 221.525µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:50:44.078568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.164706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:50:44.078725Z","caller":"traceutil/trace.go:171","msg":"trace[1875704272] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:684; }","duration":"196.39804ms","start":"2026-04-28T13:50:43.882288Z","end":"2026-04-28T13:50:44.078686Z","steps":["trace[1875704272] 'agreement among raft nodes before linearized reading' (duration: 196.029813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:44.078616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.027079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T13:50:44.078978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.119584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T13:50:44.078976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.833415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:50:44.079013Z","caller":"traceutil/trace.go:171","msg":"trace[1716522124] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:684; }","duration":"196.307688ms","start":"2026-04-28T13:50:43.882694Z","end":"2026-04-28T13:50:44.079001Z","steps":["trace[1716522124] 'agreement among raft nodes before linearized reading' (duration: 196.120585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:44.078992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.12457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:50:44.079032Z","caller":"traceutil/trace.go:171","msg":"trace[729058525] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:684; }","duration":"335.219511ms","start":"2026-04-28T13:50:43.743798Z","end":"2026-04-28T13:50:44.079017Z","steps":["trace[729058525] 'agreement among raft nodes before linearized reading' (duration: 334.848664ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:50:44.07903Z","caller":"traceutil/trace.go:171","msg":"trace[1835420380] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:684; }","duration":"334.460778ms","start":"2026-04-28T13:50:43.744526Z","end":"2026-04-28T13:50:44.078987Z","steps":["trace[1835420380] 'agreement among raft nodes before linearized reading' (duration: 333.883137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:44.079077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:43.743776Z","time spent":"335.284113ms","remote":"127.0.0.1:35182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":113,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-28T13:50:44.079161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:43.744468Z","time spent":"334.645491ms","remote":"127.0.0.1:35182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 "} {"level":"info","ts":"2026-04-28T13:50:44.079074Z","caller":"traceutil/trace.go:171","msg":"trace[1293511010] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:684; }","duration":"335.225982ms","start":"2026-04-28T13:50:43.743825Z","end":"2026-04-28T13:50:44.079051Z","steps":["trace[1293511010] 'agreement among raft nodes before linearized reading' (duration: 334.981247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:44.079278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:43.743776Z","time spent":"335.489138ms","remote":"127.0.0.1:35182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-28T13:50:44.078901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.105936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:50:44.079352Z","caller":"traceutil/trace.go:171","msg":"trace[1434836846] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:684; }","duration":"367.591425ms","start":"2026-04-28T13:50:43.711749Z","end":"2026-04-28T13:50:44.07934Z","steps":["trace[1434836846] 'agreement among raft nodes before linearized reading' (duration: 367.109475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:44.079383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:43.711731Z","time spent":"367.642687ms","remote":"127.0.0.1:35180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 "} {"level":"info","ts":"2026-04-28T13:50:44.079382Z","caller":"traceutil/trace.go:171","msg":"trace[1166045026] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"239.183204ms","start":"2026-04-28T13:50:43.839056Z","end":"2026-04-28T13:50:44.07824Z","steps":["trace[1166045026] 'process raft request' (duration: 238.97122ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:50:44.079761Z","caller":"traceutil/trace.go:171","msg":"trace[1662345894] transaction","detail":"{read_only:false; number_of_response:1; response_revision:684; }","duration":"236.305788ms","start":"2026-04-28T13:50:43.842475Z","end":"2026-04-28T13:50:44.078781Z","steps":["trace[1662345894] 'process raft request' (duration: 235.707107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:44.230459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.907065ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-28T13:50:44.230553Z","caller":"traceutil/trace.go:171","msg":"trace[780914316] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:713; }","duration":"152.156839ms","start":"2026-04-28T13:50:44.078378Z","end":"2026-04-28T13:50:44.230535Z","steps":["trace[780914316] 'read index received' (duration: 40.974669ms)","trace[780914316] 'applied index is now lower than readState.Index' (duration: 111.18045ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:50:44.230649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.318299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:50:44.230683Z","caller":"traceutil/trace.go:171","msg":"trace[2141996542] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:684; }","duration":"329.368579ms","start":"2026-04-28T13:50:43.901303Z","end":"2026-04-28T13:50:44.230672Z","steps":["trace[2141996542] 'agreement among raft nodes before linearized reading' (duration: 329.298538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:44.230719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:43.901298Z","time spent":"329.41169ms","remote":"127.0.0.1:35198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 "} {"level":"warn","ts":"2026-04-28T13:50:44.230733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.306527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:50:44.230848Z","caller":"traceutil/trace.go:171","msg":"trace[1889493796] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:684; }","duration":"331.462619ms","start":"2026-04-28T13:50:43.899364Z","end":"2026-04-28T13:50:44.230827Z","steps":["trace[1889493796] 'agreement among raft nodes before linearized reading' (duration: 331.250215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:44.230833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.793385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T13:50:44.230905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:43.899356Z","time spent":"331.537591ms","remote":"127.0.0.1:35198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-04-28T13:50:44.23094Z","caller":"traceutil/trace.go:171","msg":"trace[1107716475] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:684; }","duration":"331.977309ms","start":"2026-04-28T13:50:43.898944Z","end":"2026-04-28T13:50:44.230922Z","steps":["trace[1107716475] 'agreement among raft nodes before linearized reading' (duration: 331.752265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:44.23107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:43.898933Z","time spent":"332.085281ms","remote":"127.0.0.1:35198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 "} {"level":"warn","ts":"2026-04-28T13:50:44.23131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.389425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7zsgx\" ","response":"range_response_count:1 size:18516"} {"level":"info","ts":"2026-04-28T13:50:44.231356Z","caller":"traceutil/trace.go:171","msg":"trace[268915744] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7zsgx; range_end:; response_count:1; response_revision:684; }","duration":"321.448336ms","start":"2026-04-28T13:50:43.909897Z","end":"2026-04-28T13:50:44.231345Z","steps":["trace[268915744] 'agreement among raft nodes before linearized reading' (duration: 321.246252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:44.231386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:43.90989Z","time spent":"321.489367ms","remote":"127.0.0.1:35934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18539,"request content":"key:\"/registry/pods/kube-system/cilium-7zsgx\" "} {"level":"info","ts":"2026-04-28T13:50:44.23207Z","caller":"traceutil/trace.go:171","msg":"trace[678217167] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"148.420336ms","start":"2026-04-28T13:50:44.083635Z","end":"2026-04-28T13:50:44.232056Z","steps":["trace[678217167] 'process raft request' (duration: 148.1238ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:50:46.01463Z","caller":"traceutil/trace.go:171","msg":"trace[943212773] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"105.671274ms","start":"2026-04-28T13:50:45.908908Z","end":"2026-04-28T13:50:46.014579Z","steps":["trace[943212773] 'process raft request' (duration: 72.581947ms)","trace[943212773] 'compare' (duration: 32.892653ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:50:50.922329Z","caller":"traceutil/trace.go:171","msg":"trace[108417832] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"110.043768ms","start":"2026-04-28T13:50:50.81226Z","end":"2026-04-28T13:50:50.922304Z","steps":["trace[108417832] 'process raft request' (duration: 109.906966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:54.028428Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878714136845173,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T13:50:54.276443Z","caller":"traceutil/trace.go:171","msg":"trace[579963438] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"773.734062ms","start":"2026-04-28T13:50:53.502692Z","end":"2026-04-28T13:50:54.276426Z","steps":["trace[579963438] 'process raft request' (duration: 773.630149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:54.276561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:53.50267Z","time spent":"773.823543ms","remote":"127.0.0.1:36018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:50:54.525402Z","caller":"traceutil/trace.go:171","msg":"trace[1090356550] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:787; }","duration":"997.786929ms","start":"2026-04-28T13:50:53.527582Z","end":"2026-04-28T13:50:54.525369Z","steps":["trace[1090356550] 'read index received' (duration: 748.805872ms)","trace[1090356550] 'applied index is now lower than readState.Index' (duration: 248.979787ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:50:54.525465Z","caller":"traceutil/trace.go:171","msg":"trace[616681992] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"564.51939ms","start":"2026-04-28T13:50:53.960913Z","end":"2026-04-28T13:50:54.525432Z","steps":["trace[616681992] 'process raft request' (duration: 564.278786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:54.525608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:53.960879Z","time spent":"564.635763ms","remote":"127.0.0.1:35996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:50:54.525846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.687167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T13:50:54.525953Z","caller":"traceutil/trace.go:171","msg":"trace[1950219945] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:756; }","duration":"228.82793ms","start":"2026-04-28T13:50:54.297107Z","end":"2026-04-28T13:50:54.525935Z","steps":["trace[1950219945] 'agreement among raft nodes before linearized reading' (duration: 228.442252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:54.525942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.32074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T13:50:54.52625Z","caller":"traceutil/trace.go:171","msg":"trace[1174682171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:756; }","duration":"998.644916ms","start":"2026-04-28T13:50:53.527529Z","end":"2026-04-28T13:50:54.526173Z","steps":["trace[1174682171] 'agreement among raft nodes before linearized reading' (duration: 998.018103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:54.526367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:53.527506Z","time spent":"998.805288ms","remote":"127.0.0.1:36018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T13:51:21.370124Z","caller":"traceutil/trace.go:171","msg":"trace[1691136508] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"138.471268ms","start":"2026-04-28T13:51:21.231627Z","end":"2026-04-28T13:51:21.370098Z","steps":["trace[1691136508] 'process raft request' (duration: 138.325206ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:51:22.918283Z","caller":"traceutil/trace.go:171","msg":"trace[22962395] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"159.928412ms","start":"2026-04-28T13:51:22.758332Z","end":"2026-04-28T13:51:22.918261Z","steps":["trace[22962395] 'process raft request' (duration: 153.603471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:24.641338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.020778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:51:24.641398Z","caller":"traceutil/trace.go:171","msg":"trace[2094805537] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:950; }","duration":"155.13015ms","start":"2026-04-28T13:51:24.486254Z","end":"2026-04-28T13:51:24.641384Z","steps":["trace[2094805537] 'range keys from in-memory index tree' (duration: 154.949887ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:51:29.589438Z","caller":"traceutil/trace.go:171","msg":"trace[74878169] linearizableReadLoop","detail":"{readStateIndex:1015; appliedIndex:1014; }","duration":"100.020919ms","start":"2026-04-28T13:51:29.489395Z","end":"2026-04-28T13:51:29.589416Z","steps":["trace[74878169] 'read index received' (duration: 99.830625ms)","trace[74878169] 'applied index is now lower than readState.Index' (duration: 189.284µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:51:29.589461Z","caller":"traceutil/trace.go:171","msg":"trace[171641476] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"137.488219ms","start":"2026-04-28T13:51:29.451958Z","end":"2026-04-28T13:51:29.589446Z","steps":["trace[171641476] 'process raft request' (duration: 137.323916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:29.589543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.143781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:51:29.589574Z","caller":"traceutil/trace.go:171","msg":"trace[574183082] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:972; }","duration":"100.223002ms","start":"2026-04-28T13:51:29.489345Z","end":"2026-04-28T13:51:29.589568Z","steps":["trace[574183082] 'agreement among raft nodes before linearized reading' (duration: 100.160701ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:51:30.917607Z","caller":"traceutil/trace.go:171","msg":"trace[1636054925] linearizableReadLoop","detail":"{readStateIndex:1017; appliedIndex:1016; }","duration":"432.507435ms","start":"2026-04-28T13:51:30.485073Z","end":"2026-04-28T13:51:30.917581Z","steps":["trace[1636054925] 'read index received' (duration: 429.359325ms)","trace[1636054925] 'applied index is now lower than readState.Index' (duration: 3.14666ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:51:30.917752Z","caller":"traceutil/trace.go:171","msg":"trace[691823929] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"688.351616ms","start":"2026-04-28T13:51:30.229372Z","end":"2026-04-28T13:51:30.917724Z","steps":["trace[691823929] 'process raft request' (duration: 685.145415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:30.917925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:51:30.229329Z","time spent":"688.500129ms","remote":"127.0.0.1:36018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:51:30.918117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.009996ms","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-04-28T13:51:30.918249Z","caller":"traceutil/trace.go:171","msg":"trace[1219597378] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:974; }","duration":"433.191749ms","start":"2026-04-28T13:51:30.485034Z","end":"2026-04-28T13:51:30.918226Z","steps":["trace[1219597378] 'agreement among raft nodes before linearized reading' (duration: 432.863023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:30.918326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.796216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:51:30.918375Z","caller":"traceutil/trace.go:171","msg":"trace[1843784068] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:974; }","duration":"427.863467ms","start":"2026-04-28T13:51:30.4905Z","end":"2026-04-28T13:51:30.918363Z","steps":["trace[1843784068] 'agreement among raft nodes before linearized reading' (duration: 427.789796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:30.918406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:51:30.49049Z","time spent":"427.910148ms","remote":"127.0.0.1:35720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T13:51:30.918474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:51:30.485005Z","time spent":"433.332783ms","remote":"127.0.0.1:36018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T13:51:30.918644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.796385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T13:51:30.918681Z","caller":"traceutil/trace.go:171","msg":"trace[462373978] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:974; }","duration":"124.855576ms","start":"2026-04-28T13:51:30.793816Z","end":"2026-04-28T13:51:30.918671Z","steps":["trace[462373978] 'agreement among raft nodes before linearized reading' (duration: 124.739554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:31.505598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.961284ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:51:31.505836Z","caller":"traceutil/trace.go:171","msg":"trace[104063184] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1017; }","duration":"544.419822ms","start":"2026-04-28T13:51:30.961396Z","end":"2026-04-28T13:51:31.505816Z","steps":["trace[104063184] 'read index received' (duration: 400.07414ms)","trace[104063184] 'applied index is now lower than readState.Index' (duration: 144.344372ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:51:31.506005Z","caller":"traceutil/trace.go:171","msg":"trace[183883368] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"583.580968ms","start":"2026-04-28T13:51:30.922393Z","end":"2026-04-28T13:51:31.505974Z","steps":["trace[183883368] 'process raft request' (duration: 439.114793ms)","trace[183883368] 'compare' (duration: 143.72386ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:51:31.506058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.765128ms","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-04-28T13:51:31.506102Z","caller":"traceutil/trace.go:171","msg":"trace[988956414] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:976; }","duration":"341.84262ms","start":"2026-04-28T13:51:31.164244Z","end":"2026-04-28T13:51:31.506087Z","steps":["trace[988956414] 'agreement among raft nodes before linearized reading' (duration: 341.700947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:31.506103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.687777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-28T13:51:31.506139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:51:31.164224Z","time spent":"341.90539ms","remote":"127.0.0.1:36018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-28T13:51:31.506157Z","caller":"traceutil/trace.go:171","msg":"trace[301219072] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"583.283822ms","start":"2026-04-28T13:51:30.922856Z","end":"2026-04-28T13:51:31.50614Z","steps":["trace[301219072] 'process raft request' (duration: 582.858393ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:51:31.506168Z","caller":"traceutil/trace.go:171","msg":"trace[843758995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:976; }","duration":"544.79641ms","start":"2026-04-28T13:51:30.961353Z","end":"2026-04-28T13:51:31.506149Z","steps":["trace[843758995] 'agreement among raft nodes before linearized reading' (duration: 544.576655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:31.506163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:51:30.922376Z","time spent":"583.70584ms","remote":"127.0.0.1:36018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:51:31.50622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:51:30.961334Z","time spent":"544.872581ms","remote":"127.0.0.1:36018","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":"warn","ts":"2026-04-28T13:51:31.506246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:51:30.922835Z","time spent":"583.363364ms","remote":"127.0.0.1:36018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:51:54.120278Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878714136845848,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T13:51:54.203328Z","caller":"traceutil/trace.go:171","msg":"trace[1421664619] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"589.787367ms","start":"2026-04-28T13:51:53.613512Z","end":"2026-04-28T13:51:54.203299Z","steps":["trace[1421664619] 'process raft request' (duration: 589.631104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:54.203524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:51:53.613486Z","time spent":"589.916679ms","remote":"127.0.0.1:36018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:51:54.597762Z","caller":"traceutil/trace.go:171","msg":"trace[1633960577] linearizableReadLoop","detail":"{readStateIndex:1096; appliedIndex:1094; }","duration":"978.019349ms","start":"2026-04-28T13:51:53.61972Z","end":"2026-04-28T13:51:54.597739Z","steps":["trace[1633960577] 'read index received' (duration: 583.543695ms)","trace[1633960577] 'applied index is now lower than readState.Index' (duration: 394.474604ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:51:54.597899Z","caller":"traceutil/trace.go:171","msg":"trace[628203452] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"922.540311ms","start":"2026-04-28T13:51:53.675326Z","end":"2026-04-28T13:51:54.597866Z","steps":["trace[628203452] 'process raft request' (duration: 922.275416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:54.59806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.08656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-28T13:51:54.598082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:51:53.6753Z","time spent":"922.668123ms","remote":"127.0.0.1:36018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:51:54.598126Z","caller":"traceutil/trace.go:171","msg":"trace[822450450] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1048; }","duration":"978.172552ms","start":"2026-04-28T13:51:53.619936Z","end":"2026-04-28T13:51:54.598109Z","steps":["trace[822450450] 'agreement among raft nodes before linearized reading' (duration: 978.021719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:54.598179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:51:53.619928Z","time spent":"978.239124ms","remote":"127.0.0.1:36018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T13:51:54.598229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.930481ms","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-04-28T13:51:54.598293Z","caller":"traceutil/trace.go:171","msg":"trace[1737255129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1048; }","duration":"753.031903ms","start":"2026-04-28T13:51:53.845245Z","end":"2026-04-28T13:51:54.598277Z","steps":["trace[1737255129] 'agreement among raft nodes before linearized reading' (duration: 752.87738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:54.59834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:51:53.845225Z","time spent":"753.104264ms","remote":"127.0.0.1:36018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T13:51:54.598343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.617781ms","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-04-28T13:51:54.598384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.118585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T13:51:54.598395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.374228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:51:54.598479Z","caller":"traceutil/trace.go:171","msg":"trace[1523683599] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1048; }","duration":"327.277608ms","start":"2026-04-28T13:51:54.271181Z","end":"2026-04-28T13:51:54.598458Z","steps":["trace[1523683599] 'agreement among raft nodes before linearized reading' (duration: 327.089784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:54.598369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.26921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-28T13:51:54.59853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:51:54.271156Z","time spent":"327.362769ms","remote":"127.0.0.1:36156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-04-28T13:51:54.598445Z","caller":"traceutil/trace.go:171","msg":"trace[1338079472] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1048; }","duration":"978.763604ms","start":"2026-04-28T13:51:53.619669Z","end":"2026-04-28T13:51:54.598432Z","steps":["trace[1338079472] 'agreement among raft nodes before linearized reading' (duration: 978.197722ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:51:54.59857Z","caller":"traceutil/trace.go:171","msg":"trace[1826148957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1048; }","duration":"970.495455ms","start":"2026-04-28T13:51:53.628058Z","end":"2026-04-28T13:51:54.598554Z","steps":["trace[1826148957] 'agreement among raft nodes before linearized reading' (duration: 970.169768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:54.598626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:51:53.628044Z","time spent":"970.566006ms","remote":"127.0.0.1:36018","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":"warn","ts":"2026-04-28T13:51:54.598714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:51:53.619649Z","time spent":"979.05032ms","remote":"127.0.0.1:36018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-28T13:51:54.598982Z","caller":"traceutil/trace.go:171","msg":"trace[1759152874] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1048; }","duration":"108.700365ms","start":"2026-04-28T13:51:54.489878Z","end":"2026-04-28T13:51:54.598578Z","steps":["trace[1759152874] 'agreement among raft nodes before linearized reading' (duration: 108.308417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:54.598945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.613374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:51:54.60006Z","caller":"traceutil/trace.go:171","msg":"trace[997420915] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1048; }","duration":"384.648374ms","start":"2026-04-28T13:51:54.214615Z","end":"2026-04-28T13:51:54.599263Z","steps":["trace[997420915] 'agreement among raft nodes before linearized reading' (duration: 383.441821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:54.600158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:51:54.214587Z","time spent":"385.551781ms","remote":"127.0.0.1:35180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-28T13:51:54.724576Z","caller":"traceutil/trace.go:171","msg":"trace[2079119842] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"120.202427ms","start":"2026-04-28T13:51:54.604341Z","end":"2026-04-28T13:51:54.724543Z","steps":["trace[2079119842] 'process raft request' (duration: 120.117025ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:51:54.724747Z","caller":"traceutil/trace.go:171","msg":"trace[1066223683] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"121.177646ms","start":"2026-04-28T13:51:54.603529Z","end":"2026-04-28T13:51:54.724706Z","steps":["trace[1066223683] 'process raft request' (duration: 94.585624ms)","trace[1066223683] 'compare' (duration: 26.155443ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:51:54.725074Z","caller":"traceutil/trace.go:171","msg":"trace[1571430772] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"121.511063ms","start":"2026-04-28T13:51:54.603542Z","end":"2026-04-28T13:51:54.725053Z","steps":["trace[1571430772] 'process raft request' (duration: 120.861991ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:51:56.40789Z","caller":"traceutil/trace.go:171","msg":"trace[1317016240] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"195.332255ms","start":"2026-04-28T13:51:56.212532Z","end":"2026-04-28T13:51:56.407864Z","steps":["trace[1317016240] 'process raft request' (duration: 195.178792ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:51:56.815925Z","caller":"traceutil/trace.go:171","msg":"trace[1355950833] linearizableReadLoop","detail":"{readStateIndex:1102; appliedIndex:1101; }","duration":"146.83557ms","start":"2026-04-28T13:51:56.669063Z","end":"2026-04-28T13:51:56.815899Z","steps":["trace[1355950833] 'read index received' (duration: 146.611866ms)","trace[1355950833] 'applied index is now lower than readState.Index' (duration: 222.304µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:51:56.815981Z","caller":"traceutil/trace.go:171","msg":"trace[714483481] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"210.590809ms","start":"2026-04-28T13:51:56.605352Z","end":"2026-04-28T13:51:56.815943Z","steps":["trace[714483481] 'process raft request' (duration: 210.386375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:56.816218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.098226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T13:51:56.816337Z","caller":"traceutil/trace.go:171","msg":"trace[848538697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1054; }","duration":"147.30254ms","start":"2026-04-28T13:51:56.669009Z","end":"2026-04-28T13:51:56.816312Z","steps":["trace[848538697] 'agreement among raft nodes before linearized reading' (duration: 147.009873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:58.991936Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878714136845880,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T13:51:59.050881Z","caller":"traceutil/trace.go:171","msg":"trace[1560293058] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"632.896688ms","start":"2026-04-28T13:51:58.417949Z","end":"2026-04-28T13:51:59.050846Z","steps":["trace[1560293058] 'process raft request' (duration: 632.719354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:59.051051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:51:58.417924Z","time spent":"633.041201ms","remote":"127.0.0.1:36018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:51:59.166858Z","caller":"traceutil/trace.go:171","msg":"trace[449355375] linearizableReadLoop","detail":"{readStateIndex:1111; appliedIndex:1109; }","duration":"675.605321ms","start":"2026-04-28T13:51:58.491227Z","end":"2026-04-28T13:51:59.166832Z","steps":["trace[449355375] 'read index received' (duration: 559.417992ms)","trace[449355375] 'applied index is now lower than readState.Index' (duration: 116.185969ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:51:59.166941Z","caller":"traceutil/trace.go:171","msg":"trace[1073519178] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"346.174212ms","start":"2026-04-28T13:51:58.820736Z","end":"2026-04-28T13:51:59.16691Z","steps":["trace[1073519178] 'process raft request' (duration: 335.949685ms)","trace[1073519178] 'compare' (duration: 10.001233ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:51:59.167045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.803196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:51:59.167084Z","caller":"traceutil/trace.go:171","msg":"trace[1805127917] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1062; }","duration":"675.893437ms","start":"2026-04-28T13:51:58.491182Z","end":"2026-04-28T13:51:59.167075Z","steps":["trace[1805127917] 'agreement among raft nodes before linearized reading' (duration: 675.788764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:59.167092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:51:58.820708Z","time spent":"346.304586ms","remote":"127.0.0.1:36018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:51:59.167122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:51:58.491157Z","time spent":"675.957158ms","remote":"127.0.0.1:35720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T13:51:59.167234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.057168ms","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":"warn","ts":"2026-04-28T13:51:59.167503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.771614ms","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-04-28T13:51:59.16753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.652932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T13:51:59.167559Z","caller":"traceutil/trace.go:171","msg":"trace[912189639] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1062; }","duration":"249.867107ms","start":"2026-04-28T13:51:58.917679Z","end":"2026-04-28T13:51:59.167546Z","steps":["trace[912189639] 'agreement among raft nodes before linearized reading' (duration: 249.715814ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:51:59.167572Z","caller":"traceutil/trace.go:171","msg":"trace[848754952] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1062; }","duration":"317.734604ms","start":"2026-04-28T13:51:58.849825Z","end":"2026-04-28T13:51:59.167559Z","steps":["trace[848754952] 'agreement among raft nodes before linearized reading' (duration: 317.618372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:59.167603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:51:58.849803Z","time spent":"317.793185ms","remote":"127.0.0.1:36018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T13:51:59.167416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.194493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T13:51:59.167536Z","caller":"traceutil/trace.go:171","msg":"trace[272788978] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1062; }","duration":"666.382384ms","start":"2026-04-28T13:51:58.501136Z","end":"2026-04-28T13:51:59.167519Z","steps":["trace[272788978] 'agreement among raft nodes before linearized reading' (duration: 665.927476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:59.16778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:51:58.501097Z","time spent":"666.666779ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"info","ts":"2026-04-28T13:51:59.167711Z","caller":"traceutil/trace.go:171","msg":"trace[579270166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1062; }","duration":"249.53651ms","start":"2026-04-28T13:51:58.918146Z","end":"2026-04-28T13:51:59.167683Z","steps":["trace[579270166] 'agreement among raft nodes before linearized reading' (duration: 249.037131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:59.167469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.262515ms","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-04-28T13:51:59.168041Z","caller":"traceutil/trace.go:171","msg":"trace[1189510843] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1062; }","duration":"249.869405ms","start":"2026-04-28T13:51:58.91816Z","end":"2026-04-28T13:51:59.168029Z","steps":["trace[1189510843] 'agreement among raft nodes before linearized reading' (duration: 249.183163ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:51:59.385971Z","caller":"traceutil/trace.go:171","msg":"trace[73408504] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"213.639693ms","start":"2026-04-28T13:51:59.172295Z","end":"2026-04-28T13:51:59.385935Z","steps":["trace[73408504] 'process raft request' (duration: 168.959913ms)","trace[73408504] 'compare' (duration: 44.497037ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:51:59.40677Z","caller":"traceutil/trace.go:171","msg":"trace[1328463662] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"234.370942ms","start":"2026-04-28T13:51:59.172353Z","end":"2026-04-28T13:51:59.406724Z","steps":["trace[1328463662] 'process raft request' (duration: 234.171828ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:51:59.407001Z","caller":"traceutil/trace.go:171","msg":"trace[812757827] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"234.604287ms","start":"2026-04-28T13:51:59.172363Z","end":"2026-04-28T13:51:59.406967Z","steps":["trace[812757827] 'process raft request' (duration: 234.316491ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:51:59.407002Z","caller":"traceutil/trace.go:171","msg":"trace[932858508] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"234.543116ms","start":"2026-04-28T13:51:59.172438Z","end":"2026-04-28T13:51:59.406981Z","steps":["trace[932858508] 'process raft request' (duration: 234.451804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:13.067208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.428156ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-28T13:52:13.067298Z","caller":"traceutil/trace.go:171","msg":"trace[1456015209] linearizableReadLoop","detail":"{readStateIndex:1212; appliedIndex:1211; }","duration":"579.923253ms","start":"2026-04-28T13:52:12.48736Z","end":"2026-04-28T13:52:13.067283Z","steps":["trace[1456015209] 'read index received' (duration: 30.32µs)","trace[1456015209] 'applied index is now lower than readState.Index' (duration: 579.891173ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:52:13.067355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.990625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:52:13.067373Z","caller":"traceutil/trace.go:171","msg":"trace[1447067166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1157; }","duration":"580.041876ms","start":"2026-04-28T13:52:12.487323Z","end":"2026-04-28T13:52:13.067365Z","steps":["trace[1447067166] 'agreement among raft nodes before linearized reading' (duration: 579.995645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:13.067398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:52:12.487306Z","time spent":"580.086977ms","remote":"127.0.0.1:35720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T13:52:15.752366Z","caller":"traceutil/trace.go:171","msg":"trace[1891023216] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"201.949407ms","start":"2026-04-28T13:52:15.550388Z","end":"2026-04-28T13:52:15.752337Z","steps":["trace[1891023216] 'process raft request' (duration: 186.448399ms)","trace[1891023216] 'compare' (duration: 15.298965ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:52:25.777289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.159658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-28T13:52:25.777392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.867139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T13:52:25.777405Z","caller":"traceutil/trace.go:171","msg":"trace[941151198] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1198; }","duration":"208.326341ms","start":"2026-04-28T13:52:25.569055Z","end":"2026-04-28T13:52:25.777381Z","steps":["trace[941151198] 'range keys from in-memory index tree' (duration: 207.977774ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:52:25.777443Z","caller":"traceutil/trace.go:171","msg":"trace[531431266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1198; }","duration":"185.95771ms","start":"2026-04-28T13:52:25.591473Z","end":"2026-04-28T13:52:25.777431Z","steps":["trace[531431266] 'range keys from in-memory index tree' (duration: 185.742756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:25.777599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.559662ms","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-04-28T13:52:25.777645Z","caller":"traceutil/trace.go:171","msg":"trace[473066430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1198; }","duration":"185.623603ms","start":"2026-04-28T13:52:25.592008Z","end":"2026-04-28T13:52:25.777631Z","steps":["trace[473066430] 'range keys from in-memory index tree' (duration: 185.46768ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:52:25.967747Z","caller":"traceutil/trace.go:171","msg":"trace[1204213719] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"185.725476ms","start":"2026-04-28T13:52:25.781989Z","end":"2026-04-28T13:52:25.967714Z","steps":["trace[1204213719] 'process raft request' (duration: 168.296791ms)","trace[1204213719] 'compare' (duration: 16.962377ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:52:25.967862Z","caller":"traceutil/trace.go:171","msg":"trace[480314178] linearizableReadLoop","detail":"{readStateIndex:1258; appliedIndex:1256; }","duration":"162.55337ms","start":"2026-04-28T13:52:25.805285Z","end":"2026-04-28T13:52:25.967839Z","steps":["trace[480314178] 'read index received' (duration: 41.600861ms)","trace[480314178] 'applied index is now lower than readState.Index' (duration: 120.951508ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:52:25.967966Z","caller":"traceutil/trace.go:171","msg":"trace[1499993049] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"185.875379ms","start":"2026-04-28T13:52:25.782044Z","end":"2026-04-28T13:52:25.96792Z","steps":["trace[1499993049] 'process raft request' (duration: 185.726006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:25.968227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.891786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T13:52:25.968318Z","caller":"traceutil/trace.go:171","msg":"trace[200588742] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1201; }","duration":"163.077179ms","start":"2026-04-28T13:52:25.805229Z","end":"2026-04-28T13:52:25.968306Z","steps":["trace[200588742] 'agreement among raft nodes before linearized reading' (duration: 162.722563ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:52:27.959348Z","caller":"traceutil/trace.go:171","msg":"trace[446984801] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"104.165396ms","start":"2026-04-28T13:52:27.855155Z","end":"2026-04-28T13:52:27.959321Z","steps":["trace[446984801] 'process raft request' (duration: 103.991122ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:52:28.239023Z","caller":"traceutil/trace.go:171","msg":"trace[2062196190] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"186.560321ms","start":"2026-04-28T13:52:28.052435Z","end":"2026-04-28T13:52:28.238996Z","steps":["trace[2062196190] 'process raft request' (duration: 147.730964ms)","trace[2062196190] 'compare' (duration: 38.713125ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:52:28.861126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.35162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:52:28.861216Z","caller":"traceutil/trace.go:171","msg":"trace[5588164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1218; }","duration":"371.491573ms","start":"2026-04-28T13:52:28.489702Z","end":"2026-04-28T13:52:28.861193Z","steps":["trace[5588164] 'range keys from in-memory index tree' (duration: 371.270018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:28.861283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:52:28.489648Z","time spent":"371.621705ms","remote":"127.0.0.1:35720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T13:52:29.854519Z","caller":"traceutil/trace.go:171","msg":"trace[2027806315] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"653.673624ms","start":"2026-04-28T13:52:29.200827Z","end":"2026-04-28T13:52:29.854501Z","steps":["trace[2027806315] 'process raft request' (duration: 653.570642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:29.854645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:52:29.200814Z","time spent":"653.760695ms","remote":"127.0.0.1:36018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:52:30.355934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.858849ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:52:30.356033Z","caller":"traceutil/trace.go:171","msg":"trace[570551412] linearizableReadLoop","detail":"{readStateIndex:1278; appliedIndex:1277; }","duration":"392.844582ms","start":"2026-04-28T13:52:29.963171Z","end":"2026-04-28T13:52:30.356016Z","steps":["trace[570551412] 'read index received' (duration: 23.17µs)","trace[570551412] 'applied index is now lower than readState.Index' (duration: 392.820081ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:52:30.356337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.471195ms","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-04-28T13:52:30.3564Z","caller":"traceutil/trace.go:171","msg":"trace[415343316] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1220; }","duration":"271.576468ms","start":"2026-04-28T13:52:30.084808Z","end":"2026-04-28T13:52:30.356384Z","steps":["trace[415343316] 'agreement among raft nodes before linearized reading' (duration: 271.410054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:30.356473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.296902ms","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-04-28T13:52:30.356516Z","caller":"traceutil/trace.go:171","msg":"trace[1186470815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1220; }","duration":"393.364703ms","start":"2026-04-28T13:52:29.963141Z","end":"2026-04-28T13:52:30.356505Z","steps":["trace[1186470815] 'agreement among raft nodes before linearized reading' (duration: 393.21318ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:52:30.356504Z","caller":"traceutil/trace.go:171","msg":"trace[1151556478] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"1.01660038s","start":"2026-04-28T13:52:29.339868Z","end":"2026-04-28T13:52:30.356468Z","steps":["trace[1151556478] 'process raft request' (duration: 618.107868ms)","trace[1151556478] 'compare' (duration: 397.569684ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:52:30.356602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.13863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T13:52:30.356659Z","caller":"traceutil/trace.go:171","msg":"trace[1506876203] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1220; }","duration":"271.225622ms","start":"2026-04-28T13:52:30.085421Z","end":"2026-04-28T13:52:30.356647Z","steps":["trace[1506876203] 'agreement among raft nodes before linearized reading' (duration: 271.059219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:30.356549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:52:29.963126Z","time spent":"393.415173ms","remote":"127.0.0.1:36018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T13:52:30.356688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:52:29.339846Z","time spent":"1.016751654s","remote":"127.0.0.1:36018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:52:30.356828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.425604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T13:52:30.356904Z","caller":"traceutil/trace.go:171","msg":"trace[2138835511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1220; }","duration":"112.533446ms","start":"2026-04-28T13:52:30.244359Z","end":"2026-04-28T13:52:30.356893Z","steps":["trace[2138835511] 'agreement among raft nodes before linearized reading' (duration: 112.098538ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:52:30.534297Z","caller":"traceutil/trace.go:171","msg":"trace[533050168] linearizableReadLoop","detail":"{readStateIndex:1282; appliedIndex:1279; }","duration":"144.996372ms","start":"2026-04-28T13:52:30.38928Z","end":"2026-04-28T13:52:30.534277Z","steps":["trace[533050168] 'read index received' (duration: 31.888954ms)","trace[533050168] 'applied index is now lower than readState.Index' (duration: 113.106568ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:52:30.534581Z","caller":"traceutil/trace.go:171","msg":"trace[1664276798] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"174.248245ms","start":"2026-04-28T13:52:30.360319Z","end":"2026-04-28T13:52:30.534568Z","steps":["trace[1664276798] 'process raft request' (duration: 161.891637ms)","trace[1664276798] 'compare' (duration: 11.634424ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:52:30.534789Z","caller":"traceutil/trace.go:171","msg":"trace[1823723841] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"174.083921ms","start":"2026-04-28T13:52:30.360695Z","end":"2026-04-28T13:52:30.534779Z","steps":["trace[1823723841] 'process raft request' (duration: 173.49797ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:52:30.534907Z","caller":"traceutil/trace.go:171","msg":"trace[1829666333] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"174.130742ms","start":"2026-04-28T13:52:30.360762Z","end":"2026-04-28T13:52:30.534893Z","steps":["trace[1829666333] 'process raft request' (duration: 173.48077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:30.535113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.811047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:52:30.535156Z","caller":"traceutil/trace.go:171","msg":"trace[730571691] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1224; }","duration":"145.899389ms","start":"2026-04-28T13:52:30.389241Z","end":"2026-04-28T13:52:30.53514Z","steps":["trace[730571691] 'agreement among raft nodes before linearized reading' (duration: 145.823237ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:52:31.665338Z","caller":"traceutil/trace.go:171","msg":"trace[1018083489] linearizableReadLoop","detail":"{readStateIndex:1284; appliedIndex:1283; }","duration":"175.994988ms","start":"2026-04-28T13:52:31.48932Z","end":"2026-04-28T13:52:31.665315Z","steps":["trace[1018083489] 'read index received' (duration: 175.797934ms)","trace[1018083489] 'applied index is now lower than readState.Index' (duration: 196.134µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:52:31.665416Z","caller":"traceutil/trace.go:171","msg":"trace[1865228907] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"183.33872ms","start":"2026-04-28T13:52:31.482037Z","end":"2026-04-28T13:52:31.665376Z","steps":["trace[1865228907] 'process raft request' (duration: 183.142706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:31.665459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.12025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:52:31.665506Z","caller":"traceutil/trace.go:171","msg":"trace[1164489959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1226; }","duration":"176.211833ms","start":"2026-04-28T13:52:31.48928Z","end":"2026-04-28T13:52:31.665491Z","steps":["trace[1164489959] 'agreement among raft nodes before linearized reading' (duration: 176.12859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:32.144434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.142249ms","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-04-28T13:52:32.144512Z","caller":"traceutil/trace.go:171","msg":"trace[1394591764] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1226; }","duration":"286.267731ms","start":"2026-04-28T13:52:31.858226Z","end":"2026-04-28T13:52:32.144493Z","steps":["trace[1394591764] 'range keys from in-memory index tree' (duration: 285.942684ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:52:32.183096Z","caller":"traceutil/trace.go:171","msg":"trace[220526999] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"236.668956ms","start":"2026-04-28T13:52:31.946406Z","end":"2026-04-28T13:52:32.183075Z","steps":["trace[220526999] 'process raft request' (duration: 236.546474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:32.490615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.770192ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-28T13:52:32.491121Z","caller":"traceutil/trace.go:171","msg":"trace[2125377438] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"341.450513ms","start":"2026-04-28T13:52:32.149635Z","end":"2026-04-28T13:52:32.491086Z","steps":["trace[2125377438] 'process raft request' (duration: 341.151578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:32.491263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:52:32.149605Z","time spent":"341.582566ms","remote":"127.0.0.1:36018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:52:32.491296Z","caller":"traceutil/trace.go:171","msg":"trace[631899269] linearizableReadLoop","detail":"{readStateIndex:1287; appliedIndex:1285; }","duration":"263.017234ms","start":"2026-04-28T13:52:32.228262Z","end":"2026-04-28T13:52:32.49128Z","steps":["trace[631899269] 'read index received' (duration: 83.507658ms)","trace[631899269] 'applied index is now lower than readState.Index' (duration: 179.507366ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:52:32.491399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.136856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:52:32.491426Z","caller":"traceutil/trace.go:171","msg":"trace[308896443] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1229; }","duration":"263.194406ms","start":"2026-04-28T13:52:32.228222Z","end":"2026-04-28T13:52:32.491416Z","steps":["trace[308896443] 'agreement among raft nodes before linearized reading' (duration: 263.133075ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:52:32.491428Z","caller":"traceutil/trace.go:171","msg":"trace[640492593] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"127.116646ms","start":"2026-04-28T13:52:32.364289Z","end":"2026-04-28T13:52:32.491405Z","steps":["trace[640492593] 'process raft request' (duration: 127.044255ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:52:32.491587Z","caller":"traceutil/trace.go:171","msg":"trace[1506901069] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"179.494726ms","start":"2026-04-28T13:52:32.312082Z","end":"2026-04-28T13:52:32.491577Z","steps":["trace[1506901069] 'process raft request' (duration: 178.928825ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:52:32.836295Z","caller":"traceutil/trace.go:171","msg":"trace[528827448] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"264.457921ms","start":"2026-04-28T13:52:32.571805Z","end":"2026-04-28T13:52:32.836263Z","steps":["trace[528827448] 'process raft request' (duration: 264.219336ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:52:32.836301Z","caller":"traceutil/trace.go:171","msg":"trace[1144460202] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"264.441021ms","start":"2026-04-28T13:52:32.571833Z","end":"2026-04-28T13:52:32.836274Z","steps":["trace[1144460202] 'process raft request' (duration: 264.338569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:33.007045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.585503ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:52:33.007532Z","caller":"traceutil/trace.go:171","msg":"trace[1464476536] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"434.664087ms","start":"2026-04-28T13:52:32.572842Z","end":"2026-04-28T13:52:33.007506Z","steps":["trace[1464476536] 'process raft request' (duration: 302.537824ms)","trace[1464476536] 'compare' (duration: 130.880519ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:52:33.00779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:52:32.572825Z","time spent":"434.915181ms","remote":"127.0.0.1:36018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:52:33.008166Z","caller":"traceutil/trace.go:171","msg":"trace[1451586371] linearizableReadLoop","detail":"{readStateIndex:1294; appliedIndex:1292; }","duration":"102.280669ms","start":"2026-04-28T13:52:32.905856Z","end":"2026-04-28T13:52:33.008137Z","steps":["trace[1451586371] 'read index received' (duration: 48.511µs)","trace[1451586371] 'applied index is now lower than readState.Index' (duration: 102.231088ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:52:33.008398Z","caller":"traceutil/trace.go:171","msg":"trace[320445307] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"426.401329ms","start":"2026-04-28T13:52:32.581939Z","end":"2026-04-28T13:52:33.00834Z","steps":["trace[320445307] 'process raft request' (duration: 425.504321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:33.008575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:52:32.581928Z","time spent":"426.549522ms","remote":"127.0.0.1:35810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":757,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T13:52:33.008754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.86713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5514"} {"level":"info","ts":"2026-04-28T13:52:33.008818Z","caller":"traceutil/trace.go:171","msg":"trace[1106920085] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1235; }","duration":"102.988673ms","start":"2026-04-28T13:52:32.905815Z","end":"2026-04-28T13:52:33.008804Z","steps":["trace[1106920085] 'agreement among raft nodes before linearized reading' (duration: 102.419443ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:33.343328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.37016ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:52:33.343414Z","caller":"traceutil/trace.go:171","msg":"trace[1877607446] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"331.305118ms","start":"2026-04-28T13:52:33.012093Z","end":"2026-04-28T13:52:33.343398Z","steps":["trace[1877607446] 'process raft request' (duration: 134.815566ms)","trace[1877607446] 'compare' (duration: 196.276268ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:52:33.343457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:52:33.012066Z","time spent":"331.374079ms","remote":"127.0.0.1:35810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":823,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T13:52:33.364213Z","caller":"traceutil/trace.go:171","msg":"trace[1974775262] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"347.937679ms","start":"2026-04-28T13:52:33.016255Z","end":"2026-04-28T13:52:33.364193Z","steps":["trace[1974775262] 'process raft request' (duration: 347.831367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:33.364335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:52:33.016239Z","time spent":"348.03169ms","remote":"127.0.0.1:35930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5640,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:52:33.622567Z","caller":"traceutil/trace.go:171","msg":"trace[962030063] linearizableReadLoop","detail":"{readStateIndex:1297; appliedIndex:1296; }","duration":"132.96624ms","start":"2026-04-28T13:52:33.489578Z","end":"2026-04-28T13:52:33.622544Z","steps":["trace[962030063] 'read index received' (duration: 118.329728ms)","trace[962030063] 'applied index is now lower than readState.Index' (duration: 14.635532ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:52:33.622658Z","caller":"traceutil/trace.go:171","msg":"trace[506285439] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"251.516461ms","start":"2026-04-28T13:52:33.371109Z","end":"2026-04-28T13:52:33.622625Z","steps":["trace[506285439] 'process raft request' (duration: 236.86974ms)","trace[506285439] 'compare' (duration: 14.473028ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:52:33.622701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.100062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:52:33.622747Z","caller":"traceutil/trace.go:171","msg":"trace[1245435684] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1238; }","duration":"133.194644ms","start":"2026-04-28T13:52:33.489542Z","end":"2026-04-28T13:52:33.622736Z","steps":["trace[1245435684] 'agreement among raft nodes before linearized reading' (duration: 133.102113ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:52:37.06418Z","caller":"traceutil/trace.go:171","msg":"trace[832064814] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"102.693267ms","start":"2026-04-28T13:52:36.961453Z","end":"2026-04-28T13:52:37.064146Z","steps":["trace[832064814] 'process raft request' (duration: 72.556297ms)","trace[832064814] 'compare' (duration: 29.977827ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:52:42.654461Z","caller":"traceutil/trace.go:171","msg":"trace[1466314816] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"210.169665ms","start":"2026-04-28T13:52:42.444256Z","end":"2026-04-28T13:52:42.654425Z","steps":["trace[1466314816] 'process raft request' (duration: 209.924221ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:52:42.654415Z","caller":"traceutil/trace.go:171","msg":"trace[1895405878] linearizableReadLoop","detail":"{readStateIndex:1370; appliedIndex:1369; }","duration":"192.645619ms","start":"2026-04-28T13:52:42.461727Z","end":"2026-04-28T13:52:42.654373Z","steps":["trace[1895405878] 'read index received' (duration: 192.424814ms)","trace[1895405878] 'applied index is now lower than readState.Index' (duration: 219.474µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:52:42.654828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.948275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:52:42.65495Z","caller":"traceutil/trace.go:171","msg":"trace[1223752172] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1309; }","duration":"193.23741ms","start":"2026-04-28T13:52:42.461692Z","end":"2026-04-28T13:52:42.654929Z","steps":["trace[1223752172] 'agreement among raft nodes before linearized reading' (duration: 192.937114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:42.675055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.974701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:52:42.675116Z","caller":"traceutil/trace.go:171","msg":"trace[1849161172] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1310; }","duration":"186.083592ms","start":"2026-04-28T13:52:42.489019Z","end":"2026-04-28T13:52:42.675103Z","steps":["trace[1849161172] 'agreement among raft nodes before linearized reading' (duration: 185.98061ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:52:42.675133Z","caller":"traceutil/trace.go:171","msg":"trace[1063666612] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"148.081391ms","start":"2026-04-28T13:52:42.527012Z","end":"2026-04-28T13:52:42.675094Z","steps":["trace[1063666612] 'process raft request' (duration: 147.874487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:42.675294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.814159ms","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-04-28T13:52:42.675401Z","caller":"traceutil/trace.go:171","msg":"trace[141870835] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1310; }","duration":"142.966132ms","start":"2026-04-28T13:52:42.532412Z","end":"2026-04-28T13:52:42.675378Z","steps":["trace[141870835] 'agreement among raft nodes before linearized reading' (duration: 142.705497ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:52:43.524338Z","caller":"traceutil/trace.go:171","msg":"trace[1320846511] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"349.443576ms","start":"2026-04-28T13:52:43.174872Z","end":"2026-04-28T13:52:43.524316Z","steps":["trace[1320846511] 'process raft request' (duration: 348.933067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:43.524481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:52:43.174842Z","time spent":"349.568749ms","remote":"127.0.0.1:36018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:52:44.709472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.117757ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:52:44.70959Z","caller":"traceutil/trace.go:171","msg":"trace[1413155563] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1380; }","duration":"512.069318ms","start":"2026-04-28T13:52:44.1975Z","end":"2026-04-28T13:52:44.709569Z","steps":["trace[1413155563] 'read index received' (duration: 237.699066ms)","trace[1413155563] 'applied index is now lower than readState.Index' (duration: 274.369022ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:52:44.70973Z","caller":"traceutil/trace.go:171","msg":"trace[1228500344] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"1.180600107s","start":"2026-04-28T13:52:43.529108Z","end":"2026-04-28T13:52:44.709708Z","steps":["trace[1228500344] 'process raft request' (duration: 906.160524ms)","trace[1228500344] 'compare' (duration: 273.682138ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:52:44.709826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.331693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:52:44.709862Z","caller":"traceutil/trace.go:171","msg":"trace[1110578789] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1320; }","duration":"512.412944ms","start":"2026-04-28T13:52:44.197437Z","end":"2026-04-28T13:52:44.70985Z","steps":["trace[1110578789] 'agreement among raft nodes before linearized reading' (duration: 512.348263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:44.709865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:52:43.529086Z","time spent":"1.180694549s","remote":"127.0.0.1:36018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:52:44.709896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:52:44.197401Z","time spent":"512.486166ms","remote":"127.0.0.1:37362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-04-28T13:52:44.711508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.996973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T13:52:44.711561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.774723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-04-28T13:52:44.71155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.156708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:52:44.711643Z","caller":"traceutil/trace.go:171","msg":"trace[496160113] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1321; }","duration":"455.890916ms","start":"2026-04-28T13:52:44.255721Z","end":"2026-04-28T13:52:44.711612Z","steps":["trace[496160113] 'agreement among raft nodes before linearized reading' (duration: 455.620021ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:52:44.711665Z","caller":"traceutil/trace.go:171","msg":"trace[805797519] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1321; }","duration":"221.349671ms","start":"2026-04-28T13:52:44.49029Z","end":"2026-04-28T13:52:44.711639Z","steps":["trace[805797519] 'agreement among raft nodes before linearized reading' (duration: 221.080026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:44.711696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:52:44.255698Z","time spent":"455.982937ms","remote":"127.0.0.1:36018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-28T13:52:44.711587Z","caller":"traceutil/trace.go:171","msg":"trace[286607427] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1321; }","duration":"129.158176ms","start":"2026-04-28T13:52:44.58241Z","end":"2026-04-28T13:52:44.711568Z","steps":["trace[286607427] 'agreement among raft nodes before linearized reading' (duration: 128.947972ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:52:52.923671Z","caller":"traceutil/trace.go:171","msg":"trace[1938133813] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"156.964542ms","start":"2026-04-28T13:52:52.766683Z","end":"2026-04-28T13:52:52.923647Z","steps":["trace[1938133813] 'process raft request' (duration: 156.87489ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:52:52.923733Z","caller":"traceutil/trace.go:171","msg":"trace[1902111639] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"157.4091ms","start":"2026-04-28T13:52:52.766281Z","end":"2026-04-28T13:52:52.92369Z","steps":["trace[1902111639] 'process raft request' (duration: 98.811482ms)","trace[1902111639] 'compare' (duration: 58.258421ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:52:57.367336Z","caller":"traceutil/trace.go:171","msg":"trace[1826384215] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"162.946576ms","start":"2026-04-28T13:52:57.204351Z","end":"2026-04-28T13:52:57.367297Z","steps":["trace[1826384215] 'process raft request' (duration: 103.948761ms)","trace[1826384215] 'compare' (duration: 58.734541ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:52:57.367377Z","caller":"traceutil/trace.go:171","msg":"trace[1713443338] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"162.860794ms","start":"2026-04-28T13:52:57.204493Z","end":"2026-04-28T13:52:57.367353Z","steps":["trace[1713443338] 'process raft request' (duration: 162.747682ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:52:57.367357Z","caller":"traceutil/trace.go:171","msg":"trace[2074108022] linearizableReadLoop","detail":"{readStateIndex:1445; appliedIndex:1444; }","duration":"162.928056ms","start":"2026-04-28T13:52:57.204392Z","end":"2026-04-28T13:52:57.367321Z","steps":["trace[2074108022] 'read index received' (duration: 103.91142ms)","trace[2074108022] 'applied index is now lower than readState.Index' (duration: 59.013356ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:52:57.367574Z","caller":"traceutil/trace.go:171","msg":"trace[624265031] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"162.264904ms","start":"2026-04-28T13:52:57.205277Z","end":"2026-04-28T13:52:57.367542Z","steps":["trace[624265031] 'process raft request' (duration: 162.022989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:57.367782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.367545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-28T13:52:57.367905Z","caller":"traceutil/trace.go:171","msg":"trace[386069937] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1383; }","duration":"163.535118ms","start":"2026-04-28T13:52:57.204351Z","end":"2026-04-28T13:52:57.367886Z","steps":["trace[386069937] 'agreement among raft nodes before linearized reading' (duration: 163.054278ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:52:57.797748Z","caller":"traceutil/trace.go:171","msg":"trace[1009599921] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"209.079865ms","start":"2026-04-28T13:52:57.588643Z","end":"2026-04-28T13:52:57.797723Z","steps":["trace[1009599921] 'process raft request' (duration: 208.928862ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:53:55.58139Z","caller":"traceutil/trace.go:171","msg":"trace[1056999066] transaction","detail":"{read_only:false; response_revision:1647; number_of_response:1; }","duration":"101.521872ms","start":"2026-04-28T13:53:55.479841Z","end":"2026-04-28T13:53:55.581363Z","steps":["trace[1056999066] 'process raft request' (duration: 101.176424ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:04.127439Z","caller":"traceutil/trace.go:171","msg":"trace[856479077] linearizableReadLoop","detail":"{readStateIndex:2368; appliedIndex:2367; }","duration":"125.142257ms","start":"2026-04-28T13:56:04.002268Z","end":"2026-04-28T13:56:04.12741Z","steps":["trace[856479077] 'read index received' (duration: 124.889842ms)","trace[856479077] 'applied index is now lower than readState.Index' (duration: 251.035µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:56:04.127504Z","caller":"traceutil/trace.go:171","msg":"trace[403171027] transaction","detail":"{read_only:false; response_revision:2266; number_of_response:1; }","duration":"138.861281ms","start":"2026-04-28T13:56:03.988619Z","end":"2026-04-28T13:56:04.12748Z","steps":["trace[403171027] 'process raft request' (duration: 138.560974ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:04.127629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.691061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:56:04.127686Z","caller":"traceutil/trace.go:171","msg":"trace[1662252179] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2266; }","duration":"108.784382ms","start":"2026-04-28T13:56:04.018885Z","end":"2026-04-28T13:56:04.12767Z","steps":["trace[1662252179] 'agreement among raft nodes before linearized reading' (duration: 108.68262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:04.127898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.567555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:56:04.128019Z","caller":"traceutil/trace.go:171","msg":"trace[1810519786] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:2266; }","duration":"125.782259ms","start":"2026-04-28T13:56:04.002218Z","end":"2026-04-28T13:56:04.128Z","steps":["trace[1810519786] 'agreement among raft nodes before linearized reading' (duration: 125.336051ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:04.279356Z","caller":"traceutil/trace.go:171","msg":"trace[1280583418] transaction","detail":"{read_only:false; response_revision:2267; number_of_response:1; }","duration":"138.056935ms","start":"2026-04-28T13:56:04.141264Z","end":"2026-04-28T13:56:04.279321Z","steps":["trace[1280583418] 'process raft request' (duration: 76.499921ms)","trace[1280583418] 'compare' (duration: 61.448682ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:56:04.990637Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878714136848793,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T13:56:05.326072Z","caller":"traceutil/trace.go:171","msg":"trace[482139901] linearizableReadLoop","detail":"{readStateIndex:2372; appliedIndex:2370; }","duration":"835.974615ms","start":"2026-04-28T13:56:04.490075Z","end":"2026-04-28T13:56:05.32605Z","steps":["trace[482139901] 'read index received' (duration: 775.630354ms)","trace[482139901] 'applied index is now lower than readState.Index' (duration: 60.343151ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:56:05.326327Z","caller":"traceutil/trace.go:171","msg":"trace[2024675209] transaction","detail":"{read_only:false; response_revision:2269; number_of_response:1; }","duration":"945.658765ms","start":"2026-04-28T13:56:04.38065Z","end":"2026-04-28T13:56:05.326309Z","steps":["trace[2024675209] 'process raft request' (duration: 885.037289ms)","trace[2024675209] 'compare' (duration: 60.180827ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:56:05.326437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:04.380638Z","time spent":"945.728286ms","remote":"127.0.0.1:36018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:56:05.326611Z","caller":"traceutil/trace.go:171","msg":"trace[580787225] transaction","detail":"{read_only:false; response_revision:2270; number_of_response:1; }","duration":"945.324298ms","start":"2026-04-28T13:56:04.381277Z","end":"2026-04-28T13:56:05.326601Z","steps":["trace[580787225] 'process raft request' (duration: 944.732607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:05.326679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:04.381257Z","time spent":"945.384079ms","remote":"127.0.0.1:36018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:56:05.326907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.81073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:56:05.326993Z","caller":"traceutil/trace.go:171","msg":"trace[704598388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2270; }","duration":"836.930883ms","start":"2026-04-28T13:56:04.49004Z","end":"2026-04-28T13:56:05.32697Z","steps":["trace[704598388] 'agreement among raft nodes before linearized reading' (duration: 836.77963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:05.327035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:04.490021Z","time spent":"837.005814ms","remote":"127.0.0.1:35720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T13:56:05.327149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.154022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-28T13:56:05.327151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.116022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-04-28T13:56:05.327208Z","caller":"traceutil/trace.go:171","msg":"trace[1871226679] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2270; }","duration":"442.251563ms","start":"2026-04-28T13:56:04.88494Z","end":"2026-04-28T13:56:05.327192Z","steps":["trace[1871226679] 'agreement among raft nodes before linearized reading' (duration: 442.062489ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:05.327215Z","caller":"traceutil/trace.go:171","msg":"trace[1550355411] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2270; }","duration":"169.214553ms","start":"2026-04-28T13:56:05.157986Z","end":"2026-04-28T13:56:05.3272Z","steps":["trace[1550355411] 'agreement among raft nodes before linearized reading' (duration: 169.06461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:05.327265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:04.884921Z","time spent":"442.332675ms","remote":"127.0.0.1:36018","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-04-28T13:56:05.733117Z","caller":"traceutil/trace.go:171","msg":"trace[1077249697] transaction","detail":"{read_only:false; response_revision:2273; number_of_response:1; }","duration":"198.761692ms","start":"2026-04-28T13:56:05.534325Z","end":"2026-04-28T13:56:05.733087Z","steps":["trace[1077249697] 'process raft request' (duration: 151.33648ms)","trace[1077249697] 'compare' (duration: 47.291429ms)"],"step_count":2}