{"level":"warn","ts":"2026-06-25T22:22:05.823809Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-25T22:22:05.823932Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.23: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.23:2380","--initial-cluster=instance=https://199.204.45.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.23: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-06-25T22:22:05.82402Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-25T22:22:05.824034Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-06-25T22:22:05.82406Z","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-06-25T22:22:05.824827Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"]} {"level":"info","ts":"2026-06-25T22:22:05.824914Z","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.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.23: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-06-25T22:22:05.827092Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.897735ms"} {"level":"info","ts":"2026-06-25T22:22:05.837751Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"51a5c416a4d98c86","cluster-id":"ade2045974c19907"} {"level":"info","ts":"2026-06-25T22:22:05.837823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=()"} {"level":"info","ts":"2026-06-25T22:22:05.837844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 0"} {"level":"info","ts":"2026-06-25T22:22:05.837856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 51a5c416a4d98c86 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-25T22:22:05.83787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 1"} {"level":"info","ts":"2026-06-25T22:22:05.837912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"warn","ts":"2026-06-25T22:22:05.843563Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-25T22:22:05.845944Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-25T22:22:05.847143Z","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-06-25T22:22:05.849926Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"51a5c416a4d98c86","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-25T22:22:05.853677Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"51a5c416a4d98c86","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-25T22:22:05.853769Z","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-06-25T22:22:05.853843Z","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-06-25T22:22:05.853869Z","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-06-25T22:22:05.856478Z","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-06-25T22:22:05.856666Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"51a5c416a4d98c86","initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-25T22:22:05.856694Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-25T22:22:05.856867Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-06-25T22:22:05.856881Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-06-25T22:22:05.858212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"info","ts":"2026-06-25T22:22:05.858325Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","added-peer-id":"51a5c416a4d98c86","added-peer-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-06-25T22:22:06.638933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 is starting a new election at term 1"} {"level":"info","ts":"2026-06-25T22:22:06.638992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-25T22:22:06.639042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgPreVoteResp from 51a5c416a4d98c86 at term 1"} {"level":"info","ts":"2026-06-25T22:22:06.639077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became candidate at term 2"} {"level":"info","ts":"2026-06-25T22:22:06.639093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgVoteResp from 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-06-25T22:22:06.639129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became leader at term 2"} {"level":"info","ts":"2026-06-25T22:22:06.639165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 51a5c416a4d98c86 elected leader 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-06-25T22:22:06.640273Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"51a5c416a4d98c86","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.23:2379]}","request-path":"/0/members/51a5c416a4d98c86/attributes","cluster-id":"ade2045974c19907","publish-timeout":"7s"} {"level":"info","ts":"2026-06-25T22:22:06.640313Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-25T22:22:06.640512Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-25T22:22:06.640961Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-25T22:22:06.641283Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-25T22:22:06.641312Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-25T22:22:06.641364Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","cluster-version":"3.5"} {"level":"info","ts":"2026-06-25T22:22:06.64146Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-25T22:22:06.641489Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-25T22:22:06.643944Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-25T22:22:06.644107Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.23:2379"} {"level":"info","ts":"2026-06-25T22:22:18.281431Z","caller":"traceutil/trace.go:171","msg":"trace[660390881] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"116.737936ms","start":"2026-06-25T22:22:18.16461Z","end":"2026-06-25T22:22:18.281348Z","steps":["trace[660390881] 'process raft request' (duration: 54.030355ms)","trace[660390881] 'compare' (duration: 62.353973ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:22:18.974886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.659192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:22:18.975151Z","caller":"traceutil/trace.go:171","msg":"trace[110181648] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:403; }","duration":"107.98318ms","start":"2026-06-25T22:22:18.867137Z","end":"2026-06-25T22:22:18.975121Z","steps":["trace[110181648] 'range keys from in-memory index tree' (duration: 107.567699ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:22:32.339811Z","caller":"traceutil/trace.go:171","msg":"trace[406727767] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"122.572414ms","start":"2026-06-25T22:22:32.217213Z","end":"2026-06-25T22:22:32.339786Z","steps":["trace[406727767] 'process raft request' (duration: 74.191591ms)","trace[406727767] 'compare' (duration: 48.294921ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:22:38.083077Z","caller":"traceutil/trace.go:171","msg":"trace[386249122] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"139.784121ms","start":"2026-06-25T22:22:37.943269Z","end":"2026-06-25T22:22:38.083053Z","steps":["trace[386249122] 'process raft request' (duration: 139.648808ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:22:38.392912Z","caller":"traceutil/trace.go:171","msg":"trace[980423647] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"120.371792ms","start":"2026-06-25T22:22:38.272497Z","end":"2026-06-25T22:22:38.392869Z","steps":["trace[980423647] 'process raft request' (duration: 120.143717ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:44.36484Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955638300643984,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-25T22:22:44.590808Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.016838848s","expected-duration":"1s"} {"level":"info","ts":"2026-06-25T22:22:44.591382Z","caller":"traceutil/trace.go:171","msg":"trace[260526253] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"1.017518364s","start":"2026-06-25T22:22:43.573831Z","end":"2026-06-25T22:22:44.59135Z","steps":["trace[260526253] 'process raft request' (duration: 1.017287129s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:44.592012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:43.573807Z","time spent":"1.017661438s","remote":"127.0.0.1:35398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-25T22:22:44.599781Z","caller":"traceutil/trace.go:171","msg":"trace[1838860248] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.009940236s","start":"2026-06-25T22:22:43.589817Z","end":"2026-06-25T22:22:44.599757Z","steps":["trace[1838860248] 'process raft request' (duration: 1.009517836s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:44.599905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:43.589807Z","time spent":"1.010034698s","remote":"127.0.0.1:35398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-25T22:22:44.599958Z","caller":"traceutil/trace.go:171","msg":"trace[1185613847] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:548; }","duration":"735.312041ms","start":"2026-06-25T22:22:43.864626Z","end":"2026-06-25T22:22:44.599938Z","steps":["trace[1185613847] 'read index received' (duration: 726.541494ms)","trace[1185613847] 'applied index is now lower than readState.Index' (duration: 8.769757ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:22:44.60005Z","caller":"traceutil/trace.go:171","msg":"trace[2080088170] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.00971651s","start":"2026-06-25T22:22:43.590325Z","end":"2026-06-25T22:22:44.600041Z","steps":["trace[2080088170] 'process raft request' (duration: 1.009123066s)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:22:44.600086Z","caller":"traceutil/trace.go:171","msg":"trace[1807031991] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"1.019493481s","start":"2026-06-25T22:22:43.580579Z","end":"2026-06-25T22:22:44.600072Z","steps":["trace[1807031991] 'process raft request' (duration: 1.018572899s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:44.600137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:43.580558Z","time spent":"1.019554953s","remote":"127.0.0.1:35398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-25T22:22:44.600173Z","caller":"traceutil/trace.go:171","msg":"trace[1833942744] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.008090482s","start":"2026-06-25T22:22:43.592074Z","end":"2026-06-25T22:22:44.600164Z","steps":["trace[1833942744] 'process raft request' (duration: 1.007412846s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:44.600228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:43.592056Z","time spent":"1.008149613s","remote":"127.0.0.1:35398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-25T22:22:44.600257Z","caller":"traceutil/trace.go:171","msg":"trace[1623872573] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"1.019476091s","start":"2026-06-25T22:22:43.580769Z","end":"2026-06-25T22:22:44.600246Z","steps":["trace[1623872573] 'process raft request' (duration: 1.018484238s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:44.600304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:43.580763Z","time spent":"1.019516502s","remote":"127.0.0.1:35398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-25T22:22:44.600375Z","caller":"traceutil/trace.go:171","msg":"trace[1753586490] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.010784616s","start":"2026-06-25T22:22:43.589581Z","end":"2026-06-25T22:22:44.600366Z","steps":["trace[1753586490] 'process raft request' (duration: 1.009714301s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:44.600418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:43.589555Z","time spent":"1.010843287s","remote":"127.0.0.1:35398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-25T22:22:44.600478Z","caller":"traceutil/trace.go:171","msg":"trace[1310331267] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.003822401s","start":"2026-06-25T22:22:43.596639Z","end":"2026-06-25T22:22:44.600461Z","steps":["trace[1310331267] 'process raft request' (duration: 1.002894139s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:44.600134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:43.590311Z","time spent":"1.009768091s","remote":"127.0.0.1:35398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-25T22:22:44.600542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.284297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-25T22:22:44.600572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:43.596631Z","time spent":"1.003903463s","remote":"127.0.0.1:35398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-25T22:22:44.600761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.968302ms","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-06-25T22:22:44.600765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.130551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:22:44.600787Z","caller":"traceutil/trace.go:171","msg":"trace[840170067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:541; }","duration":"109.031334ms","start":"2026-06-25T22:22:44.491749Z","end":"2026-06-25T22:22:44.600781Z","steps":["trace[840170067] 'agreement among raft nodes before linearized reading' (duration: 108.988543ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:22:44.600795Z","caller":"traceutil/trace.go:171","msg":"trace[1335557611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:541; }","duration":"736.203302ms","start":"2026-06-25T22:22:43.864583Z","end":"2026-06-25T22:22:44.600786Z","steps":["trace[1335557611] 'agreement among raft nodes before linearized reading' (duration: 736.145851ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:44.600819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:43.864562Z","time spent":"736.250464ms","remote":"127.0.0.1:35352","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-06-25T22:22:44.600587Z","caller":"traceutil/trace.go:171","msg":"trace[1827570640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:541; }","duration":"493.371219ms","start":"2026-06-25T22:22:44.107206Z","end":"2026-06-25T22:22:44.600577Z","steps":["trace[1827570640] 'agreement among raft nodes before linearized reading' (duration: 493.263826ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:44.600893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.37659ms","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-06-25T22:22:44.600924Z","caller":"traceutil/trace.go:171","msg":"trace[1244147916] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:541; }","duration":"175.442942ms","start":"2026-06-25T22:22:44.425475Z","end":"2026-06-25T22:22:44.600918Z","steps":["trace[1244147916] 'agreement among raft nodes before linearized reading' (duration: 175.397551ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:22:44.600938Z","caller":"traceutil/trace.go:171","msg":"trace[1186006750] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"1.00293996s","start":"2026-06-25T22:22:43.597989Z","end":"2026-06-25T22:22:44.600929Z","steps":["trace[1186006750] 'process raft request' (duration: 1.001599729s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:44.600986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:43.597977Z","time spent":"1.002986671s","remote":"127.0.0.1:35398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-25T22:22:44.601005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.813214ms","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-06-25T22:22:44.601021Z","caller":"traceutil/trace.go:171","msg":"trace[1689331739] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:541; }","duration":"184.865024ms","start":"2026-06-25T22:22:44.416151Z","end":"2026-06-25T22:22:44.601016Z","steps":["trace[1689331739] 'agreement among raft nodes before linearized reading' (duration: 184.834624ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:22:44.601057Z","caller":"traceutil/trace.go:171","msg":"trace[1883278306] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"995.519995ms","start":"2026-06-25T22:22:43.605528Z","end":"2026-06-25T22:22:44.601048Z","steps":["trace[1883278306] 'process raft request' (duration: 994.166033ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:44.600884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:44.107186Z","time spent":"493.679456ms","remote":"127.0.0.1:35620","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":"info","ts":"2026-06-25T22:22:44.601291Z","caller":"traceutil/trace.go:171","msg":"trace[1138227913] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"848.391022ms","start":"2026-06-25T22:22:43.752888Z","end":"2026-06-25T22:22:44.601279Z","steps":["trace[1138227913] 'process raft request' (duration: 846.990049ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:44.601359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:43.752861Z","time spent":"848.470993ms","remote":"127.0.0.1:35398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-25T22:22:44.601411Z","caller":"traceutil/trace.go:171","msg":"trace[1279677097] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"978.669497ms","start":"2026-06-25T22:22:43.622731Z","end":"2026-06-25T22:22:44.601401Z","steps":["trace[1279677097] 'process raft request' (duration: 977.022539ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:44.601162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:43.605503Z","time spent":"995.579297ms","remote":"127.0.0.1:35398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-25T22:22:44.601471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:43.622708Z","time spent":"978.733108ms","remote":"127.0.0.1:35398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-25T22:22:48.086815Z","caller":"traceutil/trace.go:171","msg":"trace[720512595] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"128.646167ms","start":"2026-06-25T22:22:47.958143Z","end":"2026-06-25T22:22:48.086789Z","steps":["trace[720512595] 'process raft request' (duration: 126.977258ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:22:48.724879Z","caller":"traceutil/trace.go:171","msg":"trace[1948717459] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"121.506909ms","start":"2026-06-25T22:22:48.603306Z","end":"2026-06-25T22:22:48.724812Z","steps":["trace[1948717459] 'process raft request' (duration: 120.905595ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:22:48.947603Z","caller":"traceutil/trace.go:171","msg":"trace[1536389611] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"216.965291ms","start":"2026-06-25T22:22:48.730613Z","end":"2026-06-25T22:22:48.947579Z","steps":["trace[1536389611] 'process raft request' (duration: 216.91988ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:22:48.947817Z","caller":"traceutil/trace.go:171","msg":"trace[512254950] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"218.093068ms","start":"2026-06-25T22:22:48.729698Z","end":"2026-06-25T22:22:48.947791Z","steps":["trace[512254950] 'process raft request' (duration: 215.042046ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:22:48.947893Z","caller":"traceutil/trace.go:171","msg":"trace[1863115029] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"218.106968ms","start":"2026-06-25T22:22:48.729771Z","end":"2026-06-25T22:22:48.947878Z","steps":["trace[1863115029] 'process raft request' (duration: 217.700389ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:22:48.949672Z","caller":"traceutil/trace.go:171","msg":"trace[286782575] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"173.078135ms","start":"2026-06-25T22:22:48.77658Z","end":"2026-06-25T22:22:48.949659Z","steps":["trace[286782575] 'process raft request' (duration: 173.014994ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:22:49.560263Z","caller":"traceutil/trace.go:171","msg":"trace[329606797] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"192.001463ms","start":"2026-06-25T22:22:49.368235Z","end":"2026-06-25T22:22:49.560236Z","steps":["trace[329606797] 'process raft request' (duration: 191.45833ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:22:50.883096Z","caller":"traceutil/trace.go:171","msg":"trace[75920869] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"113.146982ms","start":"2026-06-25T22:22:50.769926Z","end":"2026-06-25T22:22:50.883073Z","steps":["trace[75920869] 'read index received' (duration: 112.297132ms)","trace[75920869] 'applied index is now lower than readState.Index' (duration: 848.92µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:22:50.883281Z","caller":"traceutil/trace.go:171","msg":"trace[653570395] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"330.383851ms","start":"2026-06-25T22:22:50.552882Z","end":"2026-06-25T22:22:50.883266Z","steps":["trace[653570395] 'process raft request' (duration: 329.612202ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:50.883601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.658424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-25T22:22:50.883676Z","caller":"traceutil/trace.go:171","msg":"trace[1030928296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:630; }","duration":"113.769176ms","start":"2026-06-25T22:22:50.769886Z","end":"2026-06-25T22:22:50.883655Z","steps":["trace[1030928296] 'agreement among raft nodes before linearized reading' (duration: 113.54397ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:50.883855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:50.552862Z","time spent":"330.549584ms","remote":"127.0.0.1:35430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036269,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T22:22:51.218949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.053106ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T22:22:51.219071Z","caller":"traceutil/trace.go:171","msg":"trace[1043615862] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"329.732416ms","start":"2026-06-25T22:22:50.889319Z","end":"2026-06-25T22:22:51.219052Z","steps":["trace[1043615862] 'process raft request' (duration: 84.339732ms)","trace[1043615862] 'compare' (duration: 244.921983ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:22:51.219137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:50.889293Z","time spent":"329.817287ms","remote":"127.0.0.1:35620","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-06-25T22:22:51.256108Z","caller":"traceutil/trace.go:171","msg":"trace[109365603] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:656; }","duration":"304.435268ms","start":"2026-06-25T22:22:50.951653Z","end":"2026-06-25T22:22:51.256088Z","steps":["trace[109365603] 'read index received' (duration: 21.994999ms)","trace[109365603] 'applied index is now lower than readState.Index' (duration: 282.439189ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:22:51.256352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.690183ms","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-06-25T22:22:51.256401Z","caller":"traceutil/trace.go:171","msg":"trace[1764226857] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"304.768486ms","start":"2026-06-25T22:22:50.951621Z","end":"2026-06-25T22:22:51.256389Z","steps":["trace[1764226857] 'agreement among raft nodes before linearized reading' (duration: 304.593962ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:22:51.256211Z","caller":"traceutil/trace.go:171","msg":"trace[1732704518] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"309.810876ms","start":"2026-06-25T22:22:50.946359Z","end":"2026-06-25T22:22:51.25617Z","steps":["trace[1732704518] 'process raft request' (duration: 309.644982ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:51.256498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.991988ms","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-06-25T22:22:51.256513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.546271ms","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-06-25T22:22:51.256569Z","caller":"traceutil/trace.go:171","msg":"trace[1516757842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"304.08281ms","start":"2026-06-25T22:22:50.952468Z","end":"2026-06-25T22:22:51.256551Z","steps":["trace[1516757842] 'agreement among raft nodes before linearized reading' (duration: 303.866794ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:22:51.256572Z","caller":"traceutil/trace.go:171","msg":"trace[1374739714] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"304.642683ms","start":"2026-06-25T22:22:50.951916Z","end":"2026-06-25T22:22:51.256559Z","steps":["trace[1374739714] 'agreement among raft nodes before linearized reading' (duration: 304.407957ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:51.256609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:50.952463Z","time spent":"304.137741ms","remote":"127.0.0.1:35620","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-06-25T22:22:51.256613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:50.951908Z","time spent":"304.696754ms","remote":"127.0.0.1:35620","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-06-25T22:22:51.256433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:50.951608Z","time spent":"304.817247ms","remote":"127.0.0.1:35620","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-06-25T22:22:51.256859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:50.946337Z","time spent":"310.264046ms","remote":"127.0.0.1:35716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-25T22:22:51.479059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.884892ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T22:22:51.479212Z","caller":"traceutil/trace.go:171","msg":"trace[842424886] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"218.718584ms","start":"2026-06-25T22:22:51.260475Z","end":"2026-06-25T22:22:51.479194Z","steps":["trace[842424886] 'process raft request' (duration: 106.618028ms)","trace[842424886] 'compare' (duration: 111.756478ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:22:51.479388Z","caller":"traceutil/trace.go:171","msg":"trace[708775720] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:658; }","duration":"210.564772ms","start":"2026-06-25T22:22:51.268813Z","end":"2026-06-25T22:22:51.479378Z","steps":["trace[708775720] 'read index received' (duration: 28.072463ms)","trace[708775720] 'applied index is now lower than readState.Index' (duration: 182.491409ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:22:51.479494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.674364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:22:51.479537Z","caller":"traceutil/trace.go:171","msg":"trace[523016810] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:635; }","duration":"210.734746ms","start":"2026-06-25T22:22:51.268787Z","end":"2026-06-25T22:22:51.479522Z","steps":["trace[523016810] 'agreement among raft nodes before linearized reading' (duration: 210.634304ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:22:51.479884Z","caller":"traceutil/trace.go:171","msg":"trace[200415751] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"217.973527ms","start":"2026-06-25T22:22:51.261873Z","end":"2026-06-25T22:22:51.479847Z","steps":["trace[200415751] 'process raft request' (duration: 217.284421ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:22:51.67819Z","caller":"traceutil/trace.go:171","msg":"trace[924574524] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"122.278987ms","start":"2026-06-25T22:22:51.555881Z","end":"2026-06-25T22:22:51.67816Z","steps":["trace[924574524] 'process raft request' (duration: 42.683048ms)","trace[924574524] 'compare' (duration: 79.391004ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:22:52.271505Z","caller":"traceutil/trace.go:171","msg":"trace[463630828] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"385.520712ms","start":"2026-06-25T22:22:51.885894Z","end":"2026-06-25T22:22:52.271414Z","steps":["trace[463630828] 'process raft request' (duration: 383.884114ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:52.271728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:51.885863Z","time spent":"385.771969ms","remote":"127.0.0.1:35430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T22:22:53.379607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.207672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-r6srv\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-06-25T22:22:53.379691Z","caller":"traceutil/trace.go:171","msg":"trace[477759514] range","detail":"{range_begin:/registry/pods/kube-system/cilium-r6srv; range_end:; response_count:1; response_revision:641; }","duration":"697.323674ms","start":"2026-06-25T22:22:52.682345Z","end":"2026-06-25T22:22:53.379669Z","steps":["trace[477759514] 'range keys from in-memory index tree' (duration: 697.019307ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:53.379733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:52.68233Z","time spent":"697.393467ms","remote":"127.0.0.1:35538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-r6srv\" "} {"level":"info","ts":"2026-06-25T22:22:53.379927Z","caller":"traceutil/trace.go:171","msg":"trace[507099025] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"515.957063ms","start":"2026-06-25T22:22:52.863942Z","end":"2026-06-25T22:22:53.379899Z","steps":["trace[507099025] 'read index received' (duration: 489.473547ms)","trace[507099025] 'applied index is now lower than readState.Index' (duration: 26.482446ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:22:53.380056Z","caller":"traceutil/trace.go:171","msg":"trace[1373966180] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"643.682718ms","start":"2026-06-25T22:22:52.736362Z","end":"2026-06-25T22:22:53.380044Z","steps":["trace[1373966180] 'process raft request' (duration: 617.49755ms)","trace[1373966180] 'compare' (duration: 25.709437ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:22:53.380164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:52.736337Z","time spent":"643.752209ms","remote":"127.0.0.1:35430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036321,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T22:22:53.380197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.023674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-25T22:22:53.380234Z","caller":"traceutil/trace.go:171","msg":"trace[957653783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:642; }","duration":"156.095785ms","start":"2026-06-25T22:22:53.224128Z","end":"2026-06-25T22:22:53.380224Z","steps":["trace[957653783] 'agreement among raft nodes before linearized reading' (duration: 155.989713ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:53.380418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.479155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:22:53.380453Z","caller":"traceutil/trace.go:171","msg":"trace[1135552753] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"516.540786ms","start":"2026-06-25T22:22:52.863903Z","end":"2026-06-25T22:22:53.380444Z","steps":["trace[1135552753] 'agreement among raft nodes before linearized reading' (duration: 516.488834ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:53.380481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:52.863886Z","time spent":"516.588127ms","remote":"127.0.0.1:35338","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-06-25T22:22:53.609749Z","caller":"traceutil/trace.go:171","msg":"trace[1574285995] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"223.565279ms","start":"2026-06-25T22:22:53.386154Z","end":"2026-06-25T22:22:53.609719Z","steps":["trace[1574285995] 'process raft request' (duration: 124.818637ms)","trace[1574285995] 'compare' (duration: 98.559818ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:22:53.659877Z","caller":"traceutil/trace.go:171","msg":"trace[2079560852] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"273.341124ms","start":"2026-06-25T22:22:53.386512Z","end":"2026-06-25T22:22:53.659853Z","steps":["trace[2079560852] 'process raft request' (duration: 272.989226ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:22:53.660194Z","caller":"traceutil/trace.go:171","msg":"trace[702597587] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:668; }","duration":"176.386085ms","start":"2026-06-25T22:22:53.48379Z","end":"2026-06-25T22:22:53.660176Z","steps":["trace[702597587] 'read index received' (duration: 27.197442ms)","trace[702597587] 'applied index is now lower than readState.Index' (duration: 149.187832ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:22:53.660285Z","caller":"traceutil/trace.go:171","msg":"trace[1114417788] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"226.244242ms","start":"2026-06-25T22:22:53.434032Z","end":"2026-06-25T22:22:53.660277Z","steps":["trace[1114417788] 'process raft request' (duration: 225.970055ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:22:53.660183Z","caller":"traceutil/trace.go:171","msg":"trace[266613497] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"263.957843ms","start":"2026-06-25T22:22:53.396196Z","end":"2026-06-25T22:22:53.660154Z","steps":["trace[266613497] 'process raft request' (duration: 263.585714ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:53.660449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.589119ms","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-06-25T22:22:53.660511Z","caller":"traceutil/trace.go:171","msg":"trace[232598725] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:646; }","duration":"176.689582ms","start":"2026-06-25T22:22:53.483798Z","end":"2026-06-25T22:22:53.660487Z","steps":["trace[232598725] 'agreement among raft nodes before linearized reading' (duration: 176.501347ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:53.660494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.681461ms","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-06-25T22:22:53.660571Z","caller":"traceutil/trace.go:171","msg":"trace[97144489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:646; }","duration":"176.805384ms","start":"2026-06-25T22:22:53.483746Z","end":"2026-06-25T22:22:53.660551Z","steps":["trace[97144489] 'agreement among raft nodes before linearized reading' (duration: 176.6032ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:55.160538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.096730705s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T22:22:55.160853Z","caller":"traceutil/trace.go:171","msg":"trace[323575326] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"1.495563852s","start":"2026-06-25T22:22:53.665214Z","end":"2026-06-25T22:22:55.160778Z","steps":["trace[323575326] 'process raft request' (duration: 398.490029ms)","trace[323575326] 'compare' (duration: 1.096334446s)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:22:55.161117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:53.665179Z","time spent":"1.495764856s","remote":"127.0.0.1:35620","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-06-25T22:22:55.161208Z","caller":"traceutil/trace.go:171","msg":"trace[938893698] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"1.49590503s","start":"2026-06-25T22:22:53.665251Z","end":"2026-06-25T22:22:55.161156Z","steps":["trace[938893698] 'process raft request' (duration: 1.495408569s)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:22:55.161233Z","caller":"traceutil/trace.go:171","msg":"trace[602549373] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:672; }","duration":"1.494070357s","start":"2026-06-25T22:22:53.667148Z","end":"2026-06-25T22:22:55.161219Z","steps":["trace[602549373] 'read index received' (duration: 396.571894ms)","trace[602549373] 'applied index is now lower than readState.Index' (duration: 1.097496763s)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:22:55.161308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:53.665235Z","time spent":"1.496024353s","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T22:22:55.232645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.533137169s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-25T22:22:55.232719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.565540284s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-06-25T22:22:55.232775Z","caller":"traceutil/trace.go:171","msg":"trace[1488589307] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"1.533300933s","start":"2026-06-25T22:22:53.699433Z","end":"2026-06-25T22:22:55.232734Z","steps":["trace[1488589307] 'agreement among raft nodes before linearized reading' (duration: 1.533145589s)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:22:55.232842Z","caller":"traceutil/trace.go:171","msg":"trace[1768653424] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:648; }","duration":"1.565697238s","start":"2026-06-25T22:22:53.667122Z","end":"2026-06-25T22:22:55.232819Z","steps":["trace[1768653424] 'agreement among raft nodes before linearized reading' (duration: 1.4941841s)","trace[1768653424] 'range keys from in-memory index tree' (duration: 71.245192ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:22:55.232849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:53.6994Z","time spent":"1.533433446s","remote":"127.0.0.1:35352","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-06-25T22:22:55.232838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.771257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:22:55.232923Z","caller":"traceutil/trace.go:171","msg":"trace[586320542] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"368.864789ms","start":"2026-06-25T22:22:54.864027Z","end":"2026-06-25T22:22:55.232892Z","steps":["trace[586320542] 'agreement among raft nodes before linearized reading' (duration: 368.726386ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:55.232942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:53.667111Z","time spent":"1.565817971s","remote":"127.0.0.1:35548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-06-25T22:22:55.232976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:54.86401Z","time spent":"368.953962ms","remote":"127.0.0.1:35352","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-06-25T22:22:56.115755Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955638300644206,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-25T22:22:56.315233Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.074765605s","expected-duration":"1s"} {"level":"info","ts":"2026-06-25T22:22:56.315579Z","caller":"traceutil/trace.go:171","msg":"trace[351125482] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:675; }","duration":"700.852167ms","start":"2026-06-25T22:22:55.614706Z","end":"2026-06-25T22:22:56.315559Z","steps":["trace[351125482] 'read index received' (duration: 700.648632ms)","trace[351125482] 'applied index is now lower than readState.Index' (duration: 202.515µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:22:56.315634Z","caller":"traceutil/trace.go:171","msg":"trace[413615512] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"1.075228627s","start":"2026-06-25T22:22:55.240391Z","end":"2026-06-25T22:22:56.315619Z","steps":["trace[413615512] 'process raft request' (duration: 1.075038132s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:56.315729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:55.240379Z","time spent":"1.075293838s","remote":"127.0.0.1:35538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-25T22:22:56.315769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.060782ms","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-06-25T22:22:56.315802Z","caller":"traceutil/trace.go:171","msg":"trace[956713265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:649; }","duration":"701.136874ms","start":"2026-06-25T22:22:55.614656Z","end":"2026-06-25T22:22:56.315793Z","steps":["trace[956713265] 'agreement among raft nodes before linearized reading' (duration: 700.98322ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:56.315836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:55.614637Z","time spent":"701.191345ms","remote":"127.0.0.1:35620","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-06-25T22:22:56.31679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.29518ms","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-06-25T22:22:56.316827Z","caller":"traceutil/trace.go:171","msg":"trace[1135089800] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:649; }","duration":"652.360422ms","start":"2026-06-25T22:22:55.664457Z","end":"2026-06-25T22:22:56.316817Z","steps":["trace[1135089800] 'agreement among raft nodes before linearized reading' (duration: 652.242449ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:56.316851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:55.664444Z","time spent":"652.401043ms","remote":"127.0.0.1:35620","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-06-25T22:22:57.364515Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955638300644209,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-25T22:22:57.40951Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.074428308s","expected-duration":"1s"} {"level":"info","ts":"2026-06-25T22:22:57.409883Z","caller":"traceutil/trace.go:171","msg":"trace[467393555] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"1.090133259s","start":"2026-06-25T22:22:56.319701Z","end":"2026-06-25T22:22:57.409834Z","steps":["trace[467393555] 'process raft request' (duration: 1.089970235s)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:22:57.409894Z","caller":"traceutil/trace.go:171","msg":"trace[805676405] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"1.08975432s","start":"2026-06-25T22:22:56.320105Z","end":"2026-06-25T22:22:57.409859Z","steps":["trace[805676405] 'process raft request' (duration: 1.089679689s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:57.410011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:56.31969Z","time spent":"1.090242921s","remote":"127.0.0.1:35422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-25T22:22:57.410017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:56.320098Z","time spent":"1.089846443s","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T22:22:57.41015Z","caller":"traceutil/trace.go:171","msg":"trace[544442688] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"1.08972648s","start":"2026-06-25T22:22:56.320399Z","end":"2026-06-25T22:22:57.410126Z","steps":["trace[544442688] 'process raft request' (duration: 1.089433653s)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:22:57.410179Z","caller":"traceutil/trace.go:171","msg":"trace[1938639924] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:677; }","duration":"546.449722ms","start":"2026-06-25T22:22:56.863711Z","end":"2026-06-25T22:22:57.410161Z","steps":["trace[1938639924] 'read index received' (duration: 545.94356ms)","trace[1938639924] 'applied index is now lower than readState.Index' (duration: 505.062µs)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:22:57.410312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:56.320392Z","time spent":"1.089808612s","remote":"127.0.0.1:35422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-25T22:22:57.410447Z","caller":"traceutil/trace.go:171","msg":"trace[662353908] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"1.089615357s","start":"2026-06-25T22:22:56.32082Z","end":"2026-06-25T22:22:57.410435Z","steps":["trace[662353908] 'process raft request' (duration: 1.089214258s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:57.410496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.77981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:22:57.410537Z","caller":"traceutil/trace.go:171","msg":"trace[197962712] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:655; }","duration":"546.860592ms","start":"2026-06-25T22:22:56.863665Z","end":"2026-06-25T22:22:57.410525Z","steps":["trace[197962712] 'agreement among raft nodes before linearized reading' (duration: 546.78382ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:57.410538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:56.320814Z","time spent":"1.089679489s","remote":"127.0.0.1:35588","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-06-25T22:22:57.410566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:56.863645Z","time spent":"546.914773ms","remote":"127.0.0.1:35352","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-06-25T22:22:57.410573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.758429ms","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-06-25T22:22:57.410615Z","caller":"traceutil/trace.go:171","msg":"trace[454299192] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:655; }","duration":"244.870592ms","start":"2026-06-25T22:22:57.165731Z","end":"2026-06-25T22:22:57.410602Z","steps":["trace[454299192] 'agreement among raft nodes before linearized reading' (duration: 244.743449ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:57.410639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.636417ms","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-06-25T22:22:57.410706Z","caller":"traceutil/trace.go:171","msg":"trace[636248695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:655; }","duration":"244.719658ms","start":"2026-06-25T22:22:57.165966Z","end":"2026-06-25T22:22:57.410686Z","steps":["trace[636248695] 'agreement among raft nodes before linearized reading' (duration: 244.524073ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:22:57.410704Z","caller":"traceutil/trace.go:171","msg":"trace[27443560] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"1.088373397s","start":"2026-06-25T22:22:56.322313Z","end":"2026-06-25T22:22:57.410686Z","steps":["trace[27443560] 'process raft request' (duration: 1.087794193s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:57.410798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:56.322306Z","time spent":"1.08844172s","remote":"127.0.0.1:35538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T22:22:59.270143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.626763ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-25T22:22:59.270143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.631647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:22:59.270318Z","caller":"traceutil/trace.go:171","msg":"trace[236826559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:688; }","duration":"405.854522ms","start":"2026-06-25T22:22:58.864446Z","end":"2026-06-25T22:22:59.2703Z","steps":["trace[236826559] 'range keys from in-memory index tree' (duration: 405.534835ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:22:59.27031Z","caller":"traceutil/trace.go:171","msg":"trace[1295965616] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:688; }","duration":"329.808297ms","start":"2026-06-25T22:22:58.94048Z","end":"2026-06-25T22:22:59.270288Z","steps":["trace[1295965616] 'range keys from in-memory index tree' (duration: 329.606152ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:22:59.270372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:22:58.864424Z","time spent":"405.933395ms","remote":"127.0.0.1:35352","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-06-25T22:22:59.270589Z","caller":"traceutil/trace.go:171","msg":"trace[589518590] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"279.876348ms","start":"2026-06-25T22:22:58.990698Z","end":"2026-06-25T22:22:59.270575Z","steps":["trace[589518590] 'process raft request' (duration: 279.769825ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:23:01.31606Z","caller":"traceutil/trace.go:171","msg":"trace[2059211782] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"123.177239ms","start":"2026-06-25T22:23:01.192865Z","end":"2026-06-25T22:23:01.316042Z","steps":["trace[2059211782] 'process raft request' (duration: 123.074516ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:23:01.437376Z","caller":"traceutil/trace.go:171","msg":"trace[417046205] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"109.822043ms","start":"2026-06-25T22:23:01.32753Z","end":"2026-06-25T22:23:01.437352Z","steps":["trace[417046205] 'process raft request' (duration: 109.631388ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:23:01.845056Z","caller":"traceutil/trace.go:171","msg":"trace[125427924] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"105.093422ms","start":"2026-06-25T22:23:01.739941Z","end":"2026-06-25T22:23:01.845034Z","steps":["trace[125427924] 'process raft request' (duration: 104.967119ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:23:02.022625Z","caller":"traceutil/trace.go:171","msg":"trace[1283493405] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"269.79452ms","start":"2026-06-25T22:23:01.7528Z","end":"2026-06-25T22:23:02.022595Z","steps":["trace[1283493405] 'process raft request' (duration: 269.268408ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:23:02.022789Z","caller":"traceutil/trace.go:171","msg":"trace[1545925016] linearizableReadLoop","detail":"{readStateIndex:753; appliedIndex:751; }","duration":"160.458439ms","start":"2026-06-25T22:23:01.862312Z","end":"2026-06-25T22:23:02.02277Z","steps":["trace[1545925016] 'read index received' (duration: 159.823724ms)","trace[1545925016] 'applied index is now lower than readState.Index' (duration: 633.095µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:23:02.022821Z","caller":"traceutil/trace.go:171","msg":"trace[775725951] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"203.909115ms","start":"2026-06-25T22:23:01.818884Z","end":"2026-06-25T22:23:02.022793Z","steps":["trace[775725951] 'process raft request' (duration: 203.619068ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:23:02.022955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.628573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:23:02.023049Z","caller":"traceutil/trace.go:171","msg":"trace[970355759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:724; }","duration":"160.776706ms","start":"2026-06-25T22:23:01.862258Z","end":"2026-06-25T22:23:02.023035Z","steps":["trace[970355759] 'agreement among raft nodes before linearized reading' (duration: 160.589432ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:23:02.386352Z","caller":"traceutil/trace.go:171","msg":"trace[1784668736] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"183.100593ms","start":"2026-06-25T22:23:02.203178Z","end":"2026-06-25T22:23:02.386279Z","steps":["trace[1784668736] 'process raft request' (duration: 177.685365ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:23:10.800857Z","caller":"traceutil/trace.go:171","msg":"trace[201407233] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"256.805503ms","start":"2026-06-25T22:23:10.544028Z","end":"2026-06-25T22:23:10.800833Z","steps":["trace[201407233] 'read index received' (duration: 256.756002ms)","trace[201407233] 'applied index is now lower than readState.Index' (duration: 48.521µs)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:23:10.801052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.998458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:23:10.801097Z","caller":"traceutil/trace.go:171","msg":"trace[564695555] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:796; }","duration":"257.065519ms","start":"2026-06-25T22:23:10.544021Z","end":"2026-06-25T22:23:10.801086Z","steps":["trace[564695555] 'agreement among raft nodes before linearized reading' (duration: 256.942186ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:23:10.801009Z","caller":"traceutil/trace.go:171","msg":"trace[1046963370] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"348.501878ms","start":"2026-06-25T22:23:10.452444Z","end":"2026-06-25T22:23:10.800946Z","steps":["trace[1046963370] 'process raft request' (duration: 348.258392ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:23:10.801436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.720536ms","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-06-25T22:23:10.801552Z","caller":"traceutil/trace.go:171","msg":"trace[1967423111] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:796; }","duration":"144.885111ms","start":"2026-06-25T22:23:10.656649Z","end":"2026-06-25T22:23:10.801534Z","steps":["trace[1967423111] 'agreement among raft nodes before linearized reading' (duration: 144.41993ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:23:10.801583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:23:10.452407Z","time spent":"349.01945ms","remote":"127.0.0.1:35620","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-06-25T22:23:11.23921Z","caller":"traceutil/trace.go:171","msg":"trace[1680455976] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"432.786688ms","start":"2026-06-25T22:23:10.80639Z","end":"2026-06-25T22:23:11.239176Z","steps":["trace[1680455976] 'process raft request' (duration: 393.718016ms)","trace[1680455976] 'compare' (duration: 38.716524ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:23:11.239296Z","caller":"traceutil/trace.go:171","msg":"trace[856474290] linearizableReadLoop","detail":"{readStateIndex:829; appliedIndex:828; }","duration":"373.953379ms","start":"2026-06-25T22:23:10.865305Z","end":"2026-06-25T22:23:11.239258Z","steps":["trace[856474290] 'read index received' (duration: 334.744793ms)","trace[856474290] 'applied index is now lower than readState.Index' (duration: 39.206466ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:23:11.239467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:23:10.806363Z","time spent":"432.986653ms","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T22:23:11.239496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.161784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:23:11.239586Z","caller":"traceutil/trace.go:171","msg":"trace[1919051453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:797; }","duration":"374.302057ms","start":"2026-06-25T22:23:10.865264Z","end":"2026-06-25T22:23:11.239566Z","steps":["trace[1919051453] 'agreement among raft nodes before linearized reading' (duration: 374.124163ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:23:11.239633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:23:10.865248Z","time spent":"374.37689ms","remote":"127.0.0.1:35352","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-06-25T22:23:11.760711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.835246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-25T22:23:11.760711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.130611ms","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-06-25T22:23:11.760817Z","caller":"traceutil/trace.go:171","msg":"trace[1267789851] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:797; }","duration":"271.287535ms","start":"2026-06-25T22:23:11.489508Z","end":"2026-06-25T22:23:11.760795Z","steps":["trace[1267789851] 'range keys from in-memory index tree' (duration: 270.946828ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:23:11.760855Z","caller":"traceutil/trace.go:171","msg":"trace[952094134] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:797; }","duration":"229.98131ms","start":"2026-06-25T22:23:11.530803Z","end":"2026-06-25T22:23:11.760784Z","steps":["trace[952094134] 'range keys from in-memory index tree' (duration: 229.653733ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:23:11.76102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.889422ms","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-06-25T22:23:11.761149Z","caller":"traceutil/trace.go:171","msg":"trace[749873368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:797; }","duration":"137.093127ms","start":"2026-06-25T22:23:11.624029Z","end":"2026-06-25T22:23:11.761122Z","steps":["trace[749873368] 'range keys from in-memory index tree' (duration: 136.611336ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:23:11.997364Z","caller":"traceutil/trace.go:171","msg":"trace[1064006446] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"231.2498ms","start":"2026-06-25T22:23:11.76609Z","end":"2026-06-25T22:23:11.997339Z","steps":["trace[1064006446] 'process raft request' (duration: 231.052406ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:23:11.997534Z","caller":"traceutil/trace.go:171","msg":"trace[875990792] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"230.239676ms","start":"2026-06-25T22:23:11.767276Z","end":"2026-06-25T22:23:11.997516Z","steps":["trace[875990792] 'process raft request' (duration: 230.028561ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:23:13.113291Z","caller":"traceutil/trace.go:171","msg":"trace[253877169] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:832; }","duration":"250.714609ms","start":"2026-06-25T22:23:12.862553Z","end":"2026-06-25T22:23:13.113268Z","steps":["trace[253877169] 'read index received' (duration: 11.509712ms)","trace[253877169] 'applied index is now lower than readState.Index' (duration: 239.203857ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:23:13.113372Z","caller":"traceutil/trace.go:171","msg":"trace[1655429650] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"296.938501ms","start":"2026-06-25T22:23:12.816408Z","end":"2026-06-25T22:23:13.113346Z","steps":["trace[1655429650] 'process raft request' (duration: 296.692865ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:23:13.113459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.880523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:23:13.113523Z","caller":"traceutil/trace.go:171","msg":"trace[86896978] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"251.000236ms","start":"2026-06-25T22:23:12.862507Z","end":"2026-06-25T22:23:13.113508Z","steps":["trace[86896978] 'agreement among raft nodes before linearized reading' (duration: 250.860473ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:23:13.308159Z","caller":"traceutil/trace.go:171","msg":"trace[1887729983] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:834; }","duration":"193.434227ms","start":"2026-06-25T22:23:13.114682Z","end":"2026-06-25T22:23:13.308116Z","steps":["trace[1887729983] 'read index received' (duration: 145.942355ms)","trace[1887729983] 'applied index is now lower than readState.Index' (duration: 47.489432ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:23:13.308187Z","caller":"traceutil/trace.go:171","msg":"trace[128940735] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"333.652008ms","start":"2026-06-25T22:23:12.974501Z","end":"2026-06-25T22:23:13.308153Z","steps":["trace[128940735] 'process raft request' (duration: 286.048634ms)","trace[128940735] 'compare' (duration: 47.310957ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:23:13.308307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.607321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:23:13.308381Z","caller":"traceutil/trace.go:171","msg":"trace[600251177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:803; }","duration":"193.731184ms","start":"2026-06-25T22:23:13.114636Z","end":"2026-06-25T22:23:13.308367Z","steps":["trace[600251177] 'agreement among raft nodes before linearized reading' (duration: 193.623891ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:23:13.308542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:23:12.974473Z","time spent":"333.866503ms","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T22:23:17.389667Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955638300644615,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-25T22:23:17.694294Z","caller":"traceutil/trace.go:171","msg":"trace[1266302091] linearizableReadLoop","detail":"{readStateIndex:856; appliedIndex:855; }","duration":"805.272472ms","start":"2026-06-25T22:23:16.888997Z","end":"2026-06-25T22:23:17.69427Z","steps":["trace[1266302091] 'read index received' (duration: 805.042887ms)","trace[1266302091] 'applied index is now lower than readState.Index' (duration: 228.655µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:23:17.694385Z","caller":"traceutil/trace.go:171","msg":"trace[1778596765] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"866.910147ms","start":"2026-06-25T22:23:16.827446Z","end":"2026-06-25T22:23:17.694356Z","steps":["trace[1778596765] 'process raft request' (duration: 866.673772ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:23:17.694622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.677146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:23:17.694673Z","caller":"traceutil/trace.go:171","msg":"trace[89658930] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:822; }","duration":"413.763778ms","start":"2026-06-25T22:23:17.280897Z","end":"2026-06-25T22:23:17.694661Z","steps":["trace[89658930] 'agreement among raft nodes before linearized reading' (duration: 413.657896ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:23:17.694607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.595499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-25T22:23:17.694778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:23:16.827409Z","time spent":"867.03872ms","remote":"127.0.0.1:35588","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-06-25T22:23:17.694799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.504924ms","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-06-25T22:23:17.694854Z","caller":"traceutil/trace.go:171","msg":"trace[577409363] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:822; }","duration":"347.623867ms","start":"2026-06-25T22:23:17.347215Z","end":"2026-06-25T22:23:17.694839Z","steps":["trace[577409363] 'agreement among raft nodes before linearized reading' (duration: 347.298259ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:23:17.694903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:23:17.34719Z","time spent":"347.702359ms","remote":"127.0.0.1:35620","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-06-25T22:23:17.694849Z","caller":"traceutil/trace.go:171","msg":"trace[1526324963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:822; }","duration":"805.850546ms","start":"2026-06-25T22:23:16.888939Z","end":"2026-06-25T22:23:17.69479Z","steps":["trace[1526324963] 'agreement among raft nodes before linearized reading' (duration: 805.513738ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:23:17.695017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:23:16.88891Z","time spent":"806.073971ms","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-25T22:23:17.694704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:23:17.280873Z","time spent":"413.822799ms","remote":"127.0.0.1:35740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-06-25T22:23:17.695254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.254906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:23:17.695298Z","caller":"traceutil/trace.go:171","msg":"trace[534291412] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:822; }","duration":"665.380749ms","start":"2026-06-25T22:23:17.029905Z","end":"2026-06-25T22:23:17.695286Z","steps":["trace[534291412] 'agreement among raft nodes before linearized reading' (duration: 664.58518ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:23:17.695332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:23:17.029882Z","time spent":"665.439191ms","remote":"127.0.0.1:35570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-06-25T22:23:26.009917Z","caller":"traceutil/trace.go:171","msg":"trace[1912029083] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"100.090215ms","start":"2026-06-25T22:23:25.909799Z","end":"2026-06-25T22:23:26.009889Z","steps":["trace[1912029083] 'process raft request' (duration: 99.835439ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:23:26.010013Z","caller":"traceutil/trace.go:171","msg":"trace[166344864] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"100.003733ms","start":"2026-06-25T22:23:25.909981Z","end":"2026-06-25T22:23:26.009985Z","steps":["trace[166344864] 'process raft request' (duration: 99.790398ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:23:26.192422Z","caller":"traceutil/trace.go:171","msg":"trace[29959490] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"119.226412ms","start":"2026-06-25T22:23:26.07316Z","end":"2026-06-25T22:23:26.192386Z","steps":["trace[29959490] 'process raft request' (duration: 105.621057ms)","trace[29959490] 'compare' (duration: 13.4169ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:23:26.203192Z","caller":"traceutil/trace.go:171","msg":"trace[2066943548] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"119.919529ms","start":"2026-06-25T22:23:26.083259Z","end":"2026-06-25T22:23:26.203179Z","steps":["trace[2066943548] 'process raft request' (duration: 119.777306ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:23:26.2033Z","caller":"traceutil/trace.go:171","msg":"trace[67362317] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"124.002956ms","start":"2026-06-25T22:23:26.07927Z","end":"2026-06-25T22:23:26.203273Z","steps":["trace[67362317] 'process raft request' (duration: 123.655868ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:23:29.737287Z","caller":"traceutil/trace.go:171","msg":"trace[1784962421] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"128.175726ms","start":"2026-06-25T22:23:29.609083Z","end":"2026-06-25T22:23:29.737259Z","steps":["trace[1784962421] 'process raft request' (duration: 79.767748ms)","trace[1784962421] 'compare' (duration: 48.255485ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:23:31.982702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.385498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-pfptg\" ","response":"range_response_count:1 size:5071"} {"level":"info","ts":"2026-06-25T22:23:31.982802Z","caller":"traceutil/trace.go:171","msg":"trace[849721088] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-pfptg; range_end:; response_count:1; response_revision:961; }","duration":"104.548232ms","start":"2026-06-25T22:23:31.878232Z","end":"2026-06-25T22:23:31.98278Z","steps":["trace[849721088] 'range keys from in-memory index tree' (duration: 104.07722ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:23:46.175254Z","caller":"traceutil/trace.go:171","msg":"trace[304657845] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"257.849909ms","start":"2026-06-25T22:23:45.917358Z","end":"2026-06-25T22:23:46.175208Z","steps":["trace[304657845] 'process raft request' (duration: 257.48942ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:23:46.472622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.823381ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T22:23:46.472796Z","caller":"traceutil/trace.go:171","msg":"trace[1475296667] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"293.777938ms","start":"2026-06-25T22:23:46.179Z","end":"2026-06-25T22:23:46.472778Z","steps":["trace[1475296667] 'process raft request' (duration: 293.721207ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:23:46.472802Z","caller":"traceutil/trace.go:171","msg":"trace[1097296990] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"293.85602ms","start":"2026-06-25T22:23:46.178919Z","end":"2026-06-25T22:23:46.472775Z","steps":["trace[1097296990] 'process raft request' (duration: 75.791753ms)","trace[1097296990] 'compare' (duration: 217.549875ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:23:46.512156Z","caller":"traceutil/trace.go:171","msg":"trace[2003527539] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"121.737652ms","start":"2026-06-25T22:23:46.390393Z","end":"2026-06-25T22:23:46.512131Z","steps":["trace[2003527539] 'process raft request' (duration: 121.582609ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:23:48.876712Z","caller":"traceutil/trace.go:171","msg":"trace[1829071312] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"262.314005ms","start":"2026-06-25T22:23:48.614372Z","end":"2026-06-25T22:23:48.876686Z","steps":["trace[1829071312] 'process raft request' (duration: 262.137151ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:23:50.245847Z","caller":"traceutil/trace.go:171","msg":"trace[1203998063] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"133.518975ms","start":"2026-06-25T22:23:50.112297Z","end":"2026-06-25T22:23:50.245816Z","steps":["trace[1203998063] 'process raft request' (duration: 133.254238ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:23:50.382634Z","caller":"traceutil/trace.go:171","msg":"trace[13019726] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"131.639609ms","start":"2026-06-25T22:23:50.250946Z","end":"2026-06-25T22:23:50.382586Z","steps":["trace[13019726] 'process raft request' (duration: 128.773621ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:23:50.382833Z","caller":"traceutil/trace.go:171","msg":"trace[1967377682] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"119.659272ms","start":"2026-06-25T22:23:50.263149Z","end":"2026-06-25T22:23:50.382809Z","steps":["trace[1967377682] 'process raft request' (duration: 119.248743ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:23:50.501259Z","caller":"traceutil/trace.go:171","msg":"trace[1565535286] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1028; }","duration":"109.204783ms","start":"2026-06-25T22:23:50.392026Z","end":"2026-06-25T22:23:50.501231Z","steps":["trace[1565535286] 'process raft request' (duration: 97.40187ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:23:50.687274Z","caller":"traceutil/trace.go:171","msg":"trace[230376485] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1071; }","duration":"147.820037ms","start":"2026-06-25T22:23:50.539426Z","end":"2026-06-25T22:23:50.687246Z","steps":["trace[230376485] 'read index received' (duration: 31.453503ms)","trace[230376485] 'applied index is now lower than readState.Index' (duration: 116.365374ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:23:50.687331Z","caller":"traceutil/trace.go:171","msg":"trace[960966948] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"162.832265ms","start":"2026-06-25T22:23:50.524479Z","end":"2026-06-25T22:23:50.687311Z","steps":["trace[960966948] 'process raft request' (duration: 162.717783ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:23:50.687295Z","caller":"traceutil/trace.go:171","msg":"trace[578074458] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"181.148483ms","start":"2026-06-25T22:23:50.506119Z","end":"2026-06-25T22:23:50.687267Z","steps":["trace[578074458] 'process raft request' (duration: 174.910304ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:23:50.687661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.091393ms","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-06-25T22:23:50.687718Z","caller":"traceutil/trace.go:171","msg":"trace[1968606622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1031; }","duration":"148.330429ms","start":"2026-06-25T22:23:50.539371Z","end":"2026-06-25T22:23:50.687701Z","steps":["trace[1968606622] 'agreement among raft nodes before linearized reading' (duration: 147.992191ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:24:01.07247Z","caller":"traceutil/trace.go:171","msg":"trace[1738765380] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"134.696092ms","start":"2026-06-25T22:24:00.937741Z","end":"2026-06-25T22:24:01.072437Z","steps":["trace[1738765380] 'process raft request' (duration: 134.545018ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:24:07.364784Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955638300645184,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-25T22:24:07.524399Z","caller":"traceutil/trace.go:171","msg":"trace[1979472962] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"687.804685ms","start":"2026-06-25T22:24:06.836569Z","end":"2026-06-25T22:24:07.524374Z","steps":["trace[1979472962] 'process raft request' (duration: 687.659282ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:24:07.524598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:24:06.836547Z","time spent":"687.924329ms","remote":"127.0.0.1:35620","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-06-25T22:24:07.537785Z","caller":"traceutil/trace.go:171","msg":"trace[530424952] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1127; }","duration":"673.019331ms","start":"2026-06-25T22:24:06.864729Z","end":"2026-06-25T22:24:07.537748Z","steps":["trace[530424952] 'read index received' (duration: 659.57353ms)","trace[530424952] 'applied index is now lower than readState.Index' (duration: 13.444371ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:24:07.537947Z","caller":"traceutil/trace.go:171","msg":"trace[327377806] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"699.208839ms","start":"2026-06-25T22:24:06.838724Z","end":"2026-06-25T22:24:07.537933Z","steps":["trace[327377806] 'process raft request' (duration: 698.85392ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:24:07.538106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:24:06.83871Z","time spent":"699.27008ms","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T22:24:07.538157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.184953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-25T22:24:07.538159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.406711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:24:07.538232Z","caller":"traceutil/trace.go:171","msg":"trace[1101199356] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1084; }","duration":"247.310596ms","start":"2026-06-25T22:24:07.290904Z","end":"2026-06-25T22:24:07.538215Z","steps":["trace[1101199356] 'agreement among raft nodes before linearized reading' (duration: 247.166183ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:24:07.538238Z","caller":"traceutil/trace.go:171","msg":"trace[1377857118] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1084; }","duration":"673.538814ms","start":"2026-06-25T22:24:06.864683Z","end":"2026-06-25T22:24:07.538222Z","steps":["trace[1377857118] 'agreement among raft nodes before linearized reading' (duration: 673.39767ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:24:07.538275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:24:06.864664Z","time spent":"673.602726ms","remote":"127.0.0.1:35352","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-06-25T22:24:07.538326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.328936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-25T22:24:07.538404Z","caller":"traceutil/trace.go:171","msg":"trace[1173059234] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1084; }","duration":"443.45007ms","start":"2026-06-25T22:24:07.094935Z","end":"2026-06-25T22:24:07.538385Z","steps":["trace[1173059234] 'agreement among raft nodes before linearized reading' (duration: 443.156702ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:24:07.538447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:24:07.094915Z","time spent":"443.524171ms","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-25T22:24:08.822943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.569418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-25T22:24:08.823018Z","caller":"traceutil/trace.go:171","msg":"trace[428707212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1086; }","duration":"172.682061ms","start":"2026-06-25T22:24:08.650317Z","end":"2026-06-25T22:24:08.822999Z","steps":["trace[428707212] 'range keys from in-memory index tree' (duration: 172.378693ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:24:09.043596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.562576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-25T22:24:09.043756Z","caller":"traceutil/trace.go:171","msg":"trace[1525764960] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1088; }","duration":"100.841672ms","start":"2026-06-25T22:24:08.942885Z","end":"2026-06-25T22:24:09.043726Z","steps":["trace[1525764960] 'count revisions from in-memory index tree' (duration: 100.392751ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:24:09.043492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.088742ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:24:09.04389Z","caller":"traceutil/trace.go:171","msg":"trace[1027088572] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1088; }","duration":"102.509562ms","start":"2026-06-25T22:24:08.941348Z","end":"2026-06-25T22:24:09.043857Z","steps":["trace[1027088572] 'range keys from in-memory index tree' (duration: 102.066192ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:24:31.741077Z","caller":"traceutil/trace.go:171","msg":"trace[298958086] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"102.466234ms","start":"2026-06-25T22:24:31.638562Z","end":"2026-06-25T22:24:31.741028Z","steps":["trace[298958086] 'process raft request' (duration: 101.384758ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:24:35.290251Z","caller":"traceutil/trace.go:171","msg":"trace[411111648] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"146.433967ms","start":"2026-06-25T22:24:35.143606Z","end":"2026-06-25T22:24:35.29004Z","steps":["trace[411111648] 'process raft request' (duration: 68.992412ms)","trace[411111648] 'compare' (duration: 77.285511ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:25:00.101391Z","caller":"traceutil/trace.go:171","msg":"trace[1962412387] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"115.756462ms","start":"2026-06-25T22:24:59.985167Z","end":"2026-06-25T22:25:00.100923Z","steps":["trace[1962412387] 'process raft request' (duration: 50.566971ms)","trace[1962412387] 'compare' (duration: 65.056438ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:25:07.71218Z","caller":"traceutil/trace.go:171","msg":"trace[412115339] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1335; }","duration":"254.182367ms","start":"2026-06-25T22:25:07.457974Z","end":"2026-06-25T22:25:07.712157Z","steps":["trace[412115339] 'read index received' (duration: 253.980292ms)","trace[412115339] 'applied index is now lower than readState.Index' (duration: 201.235µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:25:07.712256Z","caller":"traceutil/trace.go:171","msg":"trace[741838578] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"309.332658ms","start":"2026-06-25T22:25:07.402888Z","end":"2026-06-25T22:25:07.71222Z","steps":["trace[741838578] 'process raft request' (duration: 309.135833ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:07.712392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.406532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-25T22:25:07.71243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:07.402866Z","time spent":"309.468781ms","remote":"127.0.0.1:35620","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-06-25T22:25:07.712447Z","caller":"traceutil/trace.go:171","msg":"trace[580880319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1279; }","duration":"254.489934ms","start":"2026-06-25T22:25:07.457929Z","end":"2026-06-25T22:25:07.712419Z","steps":["trace[580880319] 'agreement among raft nodes before linearized reading' (duration: 254.329161ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:25:13.189573Z","caller":"traceutil/trace.go:171","msg":"trace[439889557] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"265.588951ms","start":"2026-06-25T22:25:12.923955Z","end":"2026-06-25T22:25:13.189544Z","steps":["trace[439889557] 'process raft request' (duration: 265.408156ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:15.296943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.625467ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-25T22:25:15.297034Z","caller":"traceutil/trace.go:171","msg":"trace[77352943] linearizableReadLoop","detail":"{readStateIndex:1362; appliedIndex:1361; }","duration":"102.107215ms","start":"2026-06-25T22:25:15.194909Z","end":"2026-06-25T22:25:15.297016Z","steps":["trace[77352943] 'read index received' (duration: 70.002µs)","trace[77352943] 'applied index is now lower than readState.Index' (duration: 102.035983ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:25:15.297275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.353111ms","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-06-25T22:25:15.297306Z","caller":"traceutil/trace.go:171","msg":"trace[850543518] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1303; }","duration":"103.224972ms","start":"2026-06-25T22:25:15.194073Z","end":"2026-06-25T22:25:15.297298Z","steps":["trace[850543518] 'agreement among raft nodes before linearized reading' (duration: 103.044898ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:25:15.715842Z","caller":"traceutil/trace.go:171","msg":"trace[1623951666] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"414.29967ms","start":"2026-06-25T22:25:15.301507Z","end":"2026-06-25T22:25:15.715806Z","steps":["trace[1623951666] 'process raft request' (duration: 414.000853ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:15.71606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:15.301489Z","time spent":"414.440803ms","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T22:25:15.968574Z","caller":"traceutil/trace.go:171","msg":"trace[1494801581] linearizableReadLoop","detail":"{readStateIndex:1364; appliedIndex:1363; }","duration":"105.536668ms","start":"2026-06-25T22:25:15.863014Z","end":"2026-06-25T22:25:15.968551Z","steps":["trace[1494801581] 'read index received' (duration: 105.375484ms)","trace[1494801581] 'applied index is now lower than readState.Index' (duration: 160.204µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:25:15.968659Z","caller":"traceutil/trace.go:171","msg":"trace[158244265] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"138.330783ms","start":"2026-06-25T22:25:15.830297Z","end":"2026-06-25T22:25:15.968628Z","steps":["trace[158244265] 'process raft request' (duration: 138.126138ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:15.968729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.693691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:25:15.968759Z","caller":"traceutil/trace.go:171","msg":"trace[1601351788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1305; }","duration":"105.765233ms","start":"2026-06-25T22:25:15.862986Z","end":"2026-06-25T22:25:15.968752Z","steps":["trace[1601351788] 'agreement among raft nodes before linearized reading' (duration: 105.696621ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:16.303929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.864201ms","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-06-25T22:25:16.303991Z","caller":"traceutil/trace.go:171","msg":"trace[874972528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1306; }","duration":"266.969683ms","start":"2026-06-25T22:25:16.037006Z","end":"2026-06-25T22:25:16.303976Z","steps":["trace[874972528] 'range keys from in-memory index tree' (duration: 266.738617ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:18.363481Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.023128709s","expected-duration":"1s"} {"level":"info","ts":"2026-06-25T22:25:18.363935Z","caller":"traceutil/trace.go:171","msg":"trace[114513852] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"2.055411631s","start":"2026-06-25T22:25:16.308498Z","end":"2026-06-25T22:25:18.36391Z","steps":["trace[114513852] 'process raft request' (duration: 2.055333129s)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:25:18.363955Z","caller":"traceutil/trace.go:171","msg":"trace[2027794608] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"2.055483483s","start":"2026-06-25T22:25:16.308435Z","end":"2026-06-25T22:25:18.363918Z","steps":["trace[2027794608] 'process raft request' (duration: 2.055253648s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:18.364091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:16.308473Z","time spent":"2.055539325s","remote":"127.0.0.1:35620","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-06-25T22:25:18.364126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:16.308409Z","time spent":"2.055631456s","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T22:25:18.364193Z","caller":"traceutil/trace.go:171","msg":"trace[635047665] linearizableReadLoop","detail":"{readStateIndex:1368; appliedIndex:1365; }","duration":"2.034573102s","start":"2026-06-25T22:25:16.329593Z","end":"2026-06-25T22:25:18.364166Z","steps":["trace[635047665] 'read index received' (duration: 10.730857ms)","trace[635047665] 'applied index is now lower than readState.Index' (duration: 2.023841065s)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:25:18.364337Z","caller":"traceutil/trace.go:171","msg":"trace[1018626687] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"2.026897208s","start":"2026-06-25T22:25:16.337396Z","end":"2026-06-25T22:25:18.364294Z","steps":["trace[1018626687] 'process raft request' (duration: 2.026476578s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:18.364361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.034743996s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:25:18.364438Z","caller":"traceutil/trace.go:171","msg":"trace[1491359325] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1310; }","duration":"2.034830159s","start":"2026-06-25T22:25:16.329566Z","end":"2026-06-25T22:25:18.364396Z","steps":["trace[1491359325] 'agreement among raft nodes before linearized reading' (duration: 2.034734997s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:18.364474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:16.329552Z","time spent":"2.0349107s","remote":"127.0.0.1:39070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-06-25T22:25:18.364487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:16.337379Z","time spent":"2.027024512s","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T22:25:18.863986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999987615s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-25T22:25:18.864073Z","caller":"traceutil/trace.go:171","msg":"trace[728133637] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000128897s","start":"2026-06-25T22:25:16.863925Z","end":"2026-06-25T22:25:18.864054Z","steps":["trace[728133637] 'agreement among raft nodes before linearized reading' (duration: 2.000017895s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:18.864121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:16.863905Z","time spent":"2.00020689s","remote":"127.0.0.1:35352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/25 22:25:18 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-25T22:25:18.864443Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955638300645799,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-25T22:25:19.641404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.74308ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:25:19.64157Z","caller":"traceutil/trace.go:171","msg":"trace[667310513] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1310; }","duration":"700.914154ms","start":"2026-06-25T22:25:18.940628Z","end":"2026-06-25T22:25:19.641542Z","steps":["trace[667310513] 'range keys from in-memory index tree' (duration: 700.726529ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:19.641604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.710549ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T22:25:19.641656Z","caller":"traceutil/trace.go:171","msg":"trace[359184258] linearizableReadLoop","detail":"{readStateIndex:1370; appliedIndex:1369; }","duration":"1.277377168s","start":"2026-06-25T22:25:18.36427Z","end":"2026-06-25T22:25:19.641648Z","steps":["trace[359184258] 'read index received' (duration: 870.538917ms)","trace[359184258] 'applied index is now lower than readState.Index' (duration: 406.837421ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:25:19.641788Z","caller":"traceutil/trace.go:171","msg":"trace[1421513021] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"1.667975013s","start":"2026-06-25T22:25:17.973804Z","end":"2026-06-25T22:25:19.641779Z","steps":["trace[1421513021] 'process raft request' (duration: 1.261032549s)","trace[1421513021] 'compare' (duration: 406.491253ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:25:19.641882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:17.973779Z","time spent":"1.668079814s","remote":"127.0.0.1:35620","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-06-25T22:25:19.642064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.298546566s","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-06-25T22:25:19.642088Z","caller":"traceutil/trace.go:171","msg":"trace[237032145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1311; }","duration":"1.298571167s","start":"2026-06-25T22:25:18.343511Z","end":"2026-06-25T22:25:19.642082Z","steps":["trace[237032145] 'agreement among raft nodes before linearized reading' (duration: 1.298489455s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:19.642107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:18.343454Z","time spent":"1.298649348s","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-25T22:25:19.642219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.648041635s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-25T22:25:19.64224Z","caller":"traceutil/trace.go:171","msg":"trace[446435305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1311; }","duration":"1.648109376s","start":"2026-06-25T22:25:17.994125Z","end":"2026-06-25T22:25:19.642235Z","steps":["trace[446435305] 'agreement among raft nodes before linearized reading' (duration: 1.648055415s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:19.642255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:17.994096Z","time spent":"1.648156368s","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-25T22:25:19.64237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.922889247s","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-06-25T22:25:19.642393Z","caller":"traceutil/trace.go:171","msg":"trace[1380408159] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1311; }","duration":"1.922945358s","start":"2026-06-25T22:25:17.719443Z","end":"2026-06-25T22:25:19.642388Z","steps":["trace[1380408159] 'agreement among raft nodes before linearized reading' (duration: 1.922891107s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:19.642409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:17.719424Z","time spent":"1.922981249s","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-25T22:25:19.642487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.236842322s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:25:19.642507Z","caller":"traceutil/trace.go:171","msg":"trace[2090700236] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1311; }","duration":"3.236892394s","start":"2026-06-25T22:25:16.405609Z","end":"2026-06-25T22:25:19.642502Z","steps":["trace[2090700236] 'agreement among raft nodes before linearized reading' (duration: 3.236861643s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:19.642523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:16.40559Z","time spent":"3.236928144s","remote":"127.0.0.1:35600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-06-25T22:25:19.642656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.217065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:25:19.642736Z","caller":"traceutil/trace.go:171","msg":"trace[1839778921] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1311; }","duration":"778.340008ms","start":"2026-06-25T22:25:18.864381Z","end":"2026-06-25T22:25:19.642721Z","steps":["trace[1839778921] 'agreement among raft nodes before linearized reading' (duration: 778.203355ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:19.642786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:18.86412Z","time spent":"778.658525ms","remote":"127.0.0.1:35338","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-06-25T22:25:19.643086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.950279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:25:19.643168Z","caller":"traceutil/trace.go:171","msg":"trace[1929864117] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1311; }","duration":"289.079422ms","start":"2026-06-25T22:25:19.354071Z","end":"2026-06-25T22:25:19.64315Z","steps":["trace[1929864117] 'agreement among raft nodes before linearized reading' (duration: 288.948599ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:25:20.833283Z","caller":"traceutil/trace.go:171","msg":"trace[1516916548] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"461.83386ms","start":"2026-06-25T22:25:20.371415Z","end":"2026-06-25T22:25:20.833248Z","steps":["trace[1516916548] 'process raft request' (duration: 461.606495ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:20.833438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:20.371397Z","time spent":"461.965823ms","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T22:25:20.83343Z","caller":"traceutil/trace.go:171","msg":"trace[1016280753] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"461.746578ms","start":"2026-06-25T22:25:20.371411Z","end":"2026-06-25T22:25:20.833158Z","steps":["trace[1016280753] 'process raft request' (duration: 461.717507ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:20.833632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:20.371386Z","time spent":"462.148267ms","remote":"127.0.0.1:35620","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-06-25T22:25:22.468175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.468825084s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-25T22:25:22.468249Z","caller":"traceutil/trace.go:171","msg":"trace[1573937532] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1318; }","duration":"1.468951167s","start":"2026-06-25T22:25:20.99928Z","end":"2026-06-25T22:25:22.468231Z","steps":["trace[1573937532] 'count revisions from in-memory index tree' (duration: 1.468739132s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:22.468233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.548472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-25T22:25:22.468289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:20.999259Z","time spent":"1.469019828s","remote":"127.0.0.1:35798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-06-25T22:25:22.468326Z","caller":"traceutil/trace.go:171","msg":"trace[460604214] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1318; }","duration":"846.694886ms","start":"2026-06-25T22:25:21.621602Z","end":"2026-06-25T22:25:22.468296Z","steps":["trace[460604214] 'count revisions from in-memory index tree' (duration: 846.43325ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:22.468371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:21.621579Z","time spent":"846.781858ms","remote":"127.0.0.1:35770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-06-25T22:25:22.469369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.312035ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T22:25:22.469423Z","caller":"traceutil/trace.go:171","msg":"trace[640538917] linearizableReadLoop","detail":"{readStateIndex:1380; appliedIndex:1379; }","duration":"720.150736ms","start":"2026-06-25T22:25:21.749262Z","end":"2026-06-25T22:25:22.469413Z","steps":["trace[640538917] 'read index received' (duration: 228.754088ms)","trace[640538917] 'applied index is now lower than readState.Index' (duration: 491.395798ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:25:22.469602Z","caller":"traceutil/trace.go:171","msg":"trace[1227965672] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"820.880098ms","start":"2026-06-25T22:25:21.648709Z","end":"2026-06-25T22:25:22.469589Z","steps":["trace[1227965672] 'process raft request' (duration: 329.296296ms)","trace[1227965672] 'compare' (duration: 490.861665ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:25:22.469665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:21.648685Z","time spent":"820.950569ms","remote":"127.0.0.1:35620","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-06-25T22:25:22.469868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.615036ms","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-06-25T22:25:22.469896Z","caller":"traceutil/trace.go:171","msg":"trace[141557830] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1319; }","duration":"720.717509ms","start":"2026-06-25T22:25:21.749169Z","end":"2026-06-25T22:25:22.469887Z","steps":["trace[141557830] 'agreement among raft nodes before linearized reading' (duration: 720.612566ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:22.469921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:21.749146Z","time spent":"720.768731ms","remote":"127.0.0.1:35620","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-06-25T22:25:22.722998Z","caller":"traceutil/trace.go:171","msg":"trace[1070016675] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"948.292809ms","start":"2026-06-25T22:25:21.774689Z","end":"2026-06-25T22:25:22.722982Z","steps":["trace[1070016675] 'process raft request' (duration: 938.881843ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:22.723032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.743045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:25:22.723062Z","caller":"traceutil/trace.go:171","msg":"trace[54717533] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1320; }","duration":"858.825297ms","start":"2026-06-25T22:25:21.864231Z","end":"2026-06-25T22:25:22.723056Z","steps":["trace[54717533] 'agreement among raft nodes before linearized reading' (duration: 858.742975ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:22.723082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:21.864212Z","time spent":"858.865187ms","remote":"127.0.0.1:35352","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-06-25T22:25:22.723112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:21.774662Z","time spent":"948.396362ms","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T22:25:22.72334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.879207ms","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-06-25T22:25:22.723363Z","caller":"traceutil/trace.go:171","msg":"trace[1187095641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1320; }","duration":"972.95167ms","start":"2026-06-25T22:25:21.750406Z","end":"2026-06-25T22:25:22.723358Z","steps":["trace[1187095641] 'agreement among raft nodes before linearized reading' (duration: 972.872458ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:22.723377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:21.750385Z","time spent":"972.98927ms","remote":"127.0.0.1:35620","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-06-25T22:25:22.722957Z","caller":"traceutil/trace.go:171","msg":"trace[681324425] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1380; }","duration":"253.495021ms","start":"2026-06-25T22:25:22.469442Z","end":"2026-06-25T22:25:22.722937Z","steps":["trace[681324425] 'read index received' (duration: 244.087235ms)","trace[681324425] 'applied index is now lower than readState.Index' (duration: 9.406846ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:25:22.724293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.911933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-25T22:25:22.724347Z","caller":"traceutil/trace.go:171","msg":"trace[695343559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1320; }","duration":"973.997284ms","start":"2026-06-25T22:25:21.750325Z","end":"2026-06-25T22:25:22.724323Z","steps":["trace[695343559] 'agreement among raft nodes before linearized reading' (duration: 973.227776ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:22.724371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:21.750309Z","time spent":"974.056756ms","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-25T22:25:22.752827Z","caller":"traceutil/trace.go:171","msg":"trace[716516860] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"278.10037ms","start":"2026-06-25T22:25:22.474692Z","end":"2026-06-25T22:25:22.752792Z","steps":["trace[716516860] 'process raft request' (duration: 277.913375ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:25:23.101786Z","caller":"traceutil/trace.go:171","msg":"trace[2012061913] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"163.958876ms","start":"2026-06-25T22:25:22.937802Z","end":"2026-06-25T22:25:23.101761Z","steps":["trace[2012061913] 'process raft request' (duration: 163.749581ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:25:23.102009Z","caller":"traceutil/trace.go:171","msg":"trace[580938322] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"164.171221ms","start":"2026-06-25T22:25:22.937801Z","end":"2026-06-25T22:25:23.101972Z","steps":["trace[580938322] 'process raft request' (duration: 163.923946ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:25:26.581539Z","caller":"traceutil/trace.go:171","msg":"trace[291667376] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"100.598459ms","start":"2026-06-25T22:25:26.480878Z","end":"2026-06-25T22:25:26.581476Z","steps":["trace[291667376] 'process raft request' (duration: 99.763359ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:25:38.896954Z","caller":"traceutil/trace.go:171","msg":"trace[500349237] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"118.155779ms","start":"2026-06-25T22:25:38.778776Z","end":"2026-06-25T22:25:38.896931Z","steps":["trace[500349237] 'process raft request' (duration: 117.728808ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:25:39.014523Z","caller":"traceutil/trace.go:171","msg":"trace[469960665] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"113.64894ms","start":"2026-06-25T22:25:38.900853Z","end":"2026-06-25T22:25:39.014502Z","steps":["trace[469960665] 'process raft request' (duration: 95.694407ms)","trace[469960665] 'compare' (duration: 17.162514ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:25:39.023477Z","caller":"traceutil/trace.go:171","msg":"trace[405747241] transaction","detail":"{read_only:false; response_revision:1425; number_of_response:1; }","duration":"121.695304ms","start":"2026-06-25T22:25:38.901762Z","end":"2026-06-25T22:25:39.023457Z","steps":["trace[405747241] 'process raft request' (duration: 121.568231ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:25:39.023648Z","caller":"traceutil/trace.go:171","msg":"trace[898124181] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"121.207142ms","start":"2026-06-25T22:25:38.902408Z","end":"2026-06-25T22:25:39.023615Z","steps":["trace[898124181] 'process raft request' (duration: 121.022928ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:41.362149Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955638300646072,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-25T22:25:41.488233Z","caller":"traceutil/trace.go:171","msg":"trace[1111856046] linearizableReadLoop","detail":"{readStateIndex:1496; appliedIndex:1495; }","duration":"626.848104ms","start":"2026-06-25T22:25:40.861365Z","end":"2026-06-25T22:25:41.488213Z","steps":["trace[1111856046] 'read index received' (duration: 626.646409ms)","trace[1111856046] 'applied index is now lower than readState.Index' (duration: 200.945µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:25:41.488296Z","caller":"traceutil/trace.go:171","msg":"trace[1327485699] transaction","detail":"{read_only:false; response_revision:1430; number_of_response:1; }","duration":"809.512707ms","start":"2026-06-25T22:25:40.678747Z","end":"2026-06-25T22:25:41.48826Z","steps":["trace[1327485699] 'process raft request' (duration: 809.341083ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:41.488417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.979157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:25:41.488448Z","caller":"traceutil/trace.go:171","msg":"trace[1724364305] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1430; }","duration":"627.112281ms","start":"2026-06-25T22:25:40.861327Z","end":"2026-06-25T22:25:41.488439Z","steps":["trace[1724364305] 'agreement among raft nodes before linearized reading' (duration: 626.995518ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:41.488474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:40.861311Z","time spent":"627.158011ms","remote":"127.0.0.1:35338","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-06-25T22:25:41.488474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:40.678728Z","time spent":"809.63501ms","remote":"127.0.0.1:35620","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-06-25T22:25:41.488543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.729316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-25T22:25:41.488598Z","caller":"traceutil/trace.go:171","msg":"trace[1172006501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1430; }","duration":"469.819738ms","start":"2026-06-25T22:25:41.018764Z","end":"2026-06-25T22:25:41.488583Z","steps":["trace[1172006501] 'agreement among raft nodes before linearized reading' (duration: 469.674545ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:41.488627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:41.018745Z","time spent":"469.876719ms","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-25T22:25:41.488734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.084009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-25T22:25:41.488749Z","caller":"traceutil/trace.go:171","msg":"trace[1366014092] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1430; }","duration":"235.098529ms","start":"2026-06-25T22:25:41.253645Z","end":"2026-06-25T22:25:41.488744Z","steps":["trace[1366014092] 'agreement among raft nodes before linearized reading' (duration: 235.046398ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:41.488821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.120612ms","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-06-25T22:25:41.488835Z","caller":"traceutil/trace.go:171","msg":"trace[319734435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1430; }","duration":"462.170343ms","start":"2026-06-25T22:25:41.026661Z","end":"2026-06-25T22:25:41.488831Z","steps":["trace[319734435] 'agreement among raft nodes before linearized reading' (duration: 462.127682ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:41.488853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:41.026643Z","time spent":"462.206604ms","remote":"127.0.0.1:35620","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-06-25T22:25:41.488853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.61937ms","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-06-25T22:25:41.488878Z","caller":"traceutil/trace.go:171","msg":"trace[60468773] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1430; }","duration":"461.670081ms","start":"2026-06-25T22:25:41.027201Z","end":"2026-06-25T22:25:41.488871Z","steps":["trace[60468773] 'agreement among raft nodes before linearized reading' (duration: 461.580719ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:41.488898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:41.027187Z","time spent":"461.706612ms","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-25T22:25:41.488956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.669954ms","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-06-25T22:25:41.488973Z","caller":"traceutil/trace.go:171","msg":"trace[15439879] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1430; }","duration":"272.729235ms","start":"2026-06-25T22:25:41.216238Z","end":"2026-06-25T22:25:41.488968Z","steps":["trace[15439879] 'agreement among raft nodes before linearized reading' (duration: 272.679494ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:55.365335Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955638300646180,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-25T22:25:55.866295Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955638300646180,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-25T22:25:56.129288Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.284663803s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-25T22:25:56.824082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.592247ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-25T22:25:56.824648Z","caller":"traceutil/trace.go:171","msg":"trace[252602129] transaction","detail":"{read_only:false; response_revision:1476; number_of_response:1; }","duration":"1.258035432s","start":"2026-06-25T22:25:55.566586Z","end":"2026-06-25T22:25:56.824621Z","steps":["trace[252602129] 'process raft request' (duration: 1.257603732s)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:25:56.824648Z","caller":"traceutil/trace.go:171","msg":"trace[929257025] linearizableReadLoop","detail":"{readStateIndex:1545; appliedIndex:1543; }","duration":"1.95976759s","start":"2026-06-25T22:25:54.864854Z","end":"2026-06-25T22:25:56.824621Z","steps":["trace[929257025] 'read index received' (duration: 1.264694822s)","trace[929257025] 'applied index is now lower than readState.Index' (duration: 695.070988ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:25:56.824776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:55.56656Z","time spent":"1.258139065s","remote":"127.0.0.1:35620","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-06-25T22:25:56.875297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.010431592s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:25:56.875389Z","caller":"traceutil/trace.go:171","msg":"trace[1934250930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1476; }","duration":"2.010544325s","start":"2026-06-25T22:25:54.86482Z","end":"2026-06-25T22:25:56.875365Z","steps":["trace[1934250930] 'agreement among raft nodes before linearized reading' (duration: 1.959867453s)","trace[1934250930] 'range keys from in-memory index tree' (duration: 50.566659ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:25:56.875432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:54.864803Z","time spent":"2.010620366s","remote":"127.0.0.1:35352","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-06-25T22:25:56.875456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.212919805s","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-06-25T22:25:56.87546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.290634758s","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-06-25T22:25:56.875492Z","caller":"traceutil/trace.go:171","msg":"trace[2095273270] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1476; }","duration":"1.212972075s","start":"2026-06-25T22:25:55.662509Z","end":"2026-06-25T22:25:56.875481Z","steps":["trace[2095273270] 'agreement among raft nodes before linearized reading' (duration: 1.162299564s)","trace[2095273270] 'range keys from in-memory index tree' (duration: 50.553359ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:25:56.87553Z","caller":"traceutil/trace.go:171","msg":"trace[1780350244] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1476; }","duration":"1.290766071s","start":"2026-06-25T22:25:55.584747Z","end":"2026-06-25T22:25:56.875513Z","steps":["trace[1780350244] 'agreement among raft nodes before linearized reading' (duration: 1.240082669s)","trace[1780350244] 'range keys from in-memory index tree' (duration: 50.501267ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:25:56.875541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:55.662503Z","time spent":"1.213032077s","remote":"127.0.0.1:35620","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-06-25T22:25:56.875571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:55.584727Z","time spent":"1.290829912s","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-25T22:25:56.875254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.347393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-25T22:25:56.875675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.305730093s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-25T22:25:56.875757Z","caller":"traceutil/trace.go:171","msg":"trace[1604829103] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1476; }","duration":"585.897877ms","start":"2026-06-25T22:25:56.289849Z","end":"2026-06-25T22:25:56.875747Z","steps":["trace[1604829103] 'agreement among raft nodes before linearized reading' (duration: 534.951609ms)","trace[1604829103] 'count revisions from in-memory index tree' (duration: 50.408085ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:25:56.875783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:56.289835Z","time spent":"585.939858ms","remote":"127.0.0.1:39176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-06-25T22:25:56.875787Z","caller":"traceutil/trace.go:171","msg":"trace[1987703760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1476; }","duration":"1.305880856s","start":"2026-06-25T22:25:55.569882Z","end":"2026-06-25T22:25:56.875762Z","steps":["trace[1987703760] 'agreement among raft nodes before linearized reading' (duration: 1.254904737s)","trace[1987703760] 'range keys from in-memory index tree' (duration: 50.770364ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:25:56.875811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.214464322s","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-06-25T22:25:56.875836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:55.569861Z","time spent":"1.305961328s","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-25T22:25:56.875856Z","caller":"traceutil/trace.go:171","msg":"trace[2137386311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1476; }","duration":"1.214527093s","start":"2026-06-25T22:25:55.661314Z","end":"2026-06-25T22:25:56.875841Z","steps":["trace[2137386311] 'agreement among raft nodes before linearized reading' (duration: 1.163509423s)","trace[2137386311] 'range keys from in-memory index tree' (duration: 50.863447ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:25:56.875891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:55.661304Z","time spent":"1.214580324s","remote":"127.0.0.1:35620","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-06-25T22:25:56.876182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.214193835s","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-06-25T22:25:56.876213Z","caller":"traceutil/trace.go:171","msg":"trace[219408669] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1476; }","duration":"1.214246086s","start":"2026-06-25T22:25:55.661959Z","end":"2026-06-25T22:25:56.876205Z","steps":["trace[219408669] 'agreement among raft nodes before linearized reading' (duration: 1.162857377s)","trace[219408669] 'range keys from in-memory index tree' (duration: 50.431306ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:25:56.87623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:55.661952Z","time spent":"1.214274077s","remote":"127.0.0.1:35620","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":"info","ts":"2026-06-25T22:25:58.921159Z","caller":"traceutil/trace.go:171","msg":"trace[74585993] linearizableReadLoop","detail":"{readStateIndex:1552; appliedIndex:1551; }","duration":"343.515022ms","start":"2026-06-25T22:25:58.57762Z","end":"2026-06-25T22:25:58.921135Z","steps":["trace[74585993] 'read index received' (duration: 343.383138ms)","trace[74585993] 'applied index is now lower than readState.Index' (duration: 130.884µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:25:58.921216Z","caller":"traceutil/trace.go:171","msg":"trace[270822227] transaction","detail":"{read_only:false; response_revision:1483; number_of_response:1; }","duration":"765.189778ms","start":"2026-06-25T22:25:58.155973Z","end":"2026-06-25T22:25:58.921163Z","steps":["trace[270822227] 'process raft request' (duration: 765.037885ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:58.92127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.637894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:25:58.921299Z","caller":"traceutil/trace.go:171","msg":"trace[544941339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1483; }","duration":"343.709106ms","start":"2026-06-25T22:25:58.577583Z","end":"2026-06-25T22:25:58.921292Z","steps":["trace[544941339] 'agreement among raft nodes before linearized reading' (duration: 343.625214ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:58.921321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:58.577567Z","time spent":"343.748977ms","remote":"127.0.0.1:35352","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-06-25T22:25:58.921372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:58.155948Z","time spent":"765.352123ms","remote":"127.0.0.1:35422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":839,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-25T22:25:59.421817Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955638300646197,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-25T22:25:59.866279Z","caller":"traceutil/trace.go:171","msg":"trace[113931761] linearizableReadLoop","detail":"{readStateIndex:1553; appliedIndex:1552; }","duration":"945.015415ms","start":"2026-06-25T22:25:58.921243Z","end":"2026-06-25T22:25:59.866259Z","steps":["trace[113931761] 'read index received' (duration: 924.123522ms)","trace[113931761] 'applied index is now lower than readState.Index' (duration: 20.891053ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:25:59.866457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.790117ms","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-06-25T22:25:59.866486Z","caller":"traceutil/trace.go:171","msg":"trace[1298889339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1484; }","duration":"975.840979ms","start":"2026-06-25T22:25:58.890638Z","end":"2026-06-25T22:25:59.866479Z","steps":["trace[1298889339] 'agreement among raft nodes before linearized reading' (duration: 975.703885ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:25:59.866432Z","caller":"traceutil/trace.go:171","msg":"trace[323216286] transaction","detail":"{read_only:false; response_revision:1484; number_of_response:1; }","duration":"1.037471164s","start":"2026-06-25T22:25:58.828869Z","end":"2026-06-25T22:25:59.86634Z","steps":["trace[323216286] 'process raft request' (duration: 1.016488458s)","trace[323216286] 'compare' (duration: 20.359451ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:25:59.866578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.105095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-25T22:25:59.866601Z","caller":"traceutil/trace.go:171","msg":"trace[662997173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1484; }","duration":"976.135395ms","start":"2026-06-25T22:25:58.89046Z","end":"2026-06-25T22:25:59.866596Z","steps":["trace[662997173] 'agreement among raft nodes before linearized reading' (duration: 976.079784ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:59.866607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.036378ms","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-06-25T22:25:59.866681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.15502ms","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-06-25T22:25:59.866705Z","caller":"traceutil/trace.go:171","msg":"trace[135705777] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1484; }","duration":"977.185541ms","start":"2026-06-25T22:25:58.889512Z","end":"2026-06-25T22:25:59.866698Z","steps":["trace[135705777] 'agreement among raft nodes before linearized reading' (duration: 977.13298ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:59.866718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.002826639s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-25T22:25:59.866754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.230596821s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:25:59.866751Z","caller":"traceutil/trace.go:171","msg":"trace[581740368] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1484; }","duration":"1.002893461s","start":"2026-06-25T22:25:58.863849Z","end":"2026-06-25T22:25:59.866743Z","steps":["trace[581740368] 'agreement among raft nodes before linearized reading' (duration: 1.00284785s)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:25:59.866778Z","caller":"traceutil/trace.go:171","msg":"trace[224229769] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1484; }","duration":"1.230654073s","start":"2026-06-25T22:25:58.636116Z","end":"2026-06-25T22:25:59.86677Z","steps":["trace[224229769] 'agreement among raft nodes before linearized reading' (duration: 1.230621272s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:59.866799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:58.636095Z","time spent":"1.230699403s","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-06-25T22:25:59.866632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:58.890456Z","time spent":"976.158856ms","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-25T22:25:59.866804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:58.828852Z","time spent":"1.03771367s","remote":"127.0.0.1:35620","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-06-25T22:25:59.866725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:58.889507Z","time spent":"977.214571ms","remote":"127.0.0.1:35620","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":"info","ts":"2026-06-25T22:25:59.866672Z","caller":"traceutil/trace.go:171","msg":"trace[82914924] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1484; }","duration":"975.094291ms","start":"2026-06-25T22:25:58.891556Z","end":"2026-06-25T22:25:59.86665Z","steps":["trace[82914924] 'agreement among raft nodes before linearized reading' (duration: 974.986918ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:59.866986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:58.891553Z","time spent":"975.408008ms","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-25T22:25:59.866499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.53837ms","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-06-25T22:25:59.866525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:58.890634Z","time spent":"975.88579ms","remote":"127.0.0.1:35620","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-06-25T22:25:59.867039Z","caller":"traceutil/trace.go:171","msg":"trace[247346661] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1484; }","duration":"976.105325ms","start":"2026-06-25T22:25:58.890927Z","end":"2026-06-25T22:25:59.867032Z","steps":["trace[247346661] 'agreement among raft nodes before linearized reading' (duration: 975.353817ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:59.867063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:58.890922Z","time spent":"976.134445ms","remote":"127.0.0.1:35620","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-06-25T22:25:59.866794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:58.86383Z","time spent":"1.002954612s","remote":"127.0.0.1:35338","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-06-25T22:25:59.872662Z","caller":"traceutil/trace.go:171","msg":"trace[1552762294] transaction","detail":"{read_only:false; response_revision:1485; number_of_response:1; }","duration":"327.337372ms","start":"2026-06-25T22:25:59.54531Z","end":"2026-06-25T22:25:59.872647Z","steps":["trace[1552762294] 'process raft request' (duration: 327.273001ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:59.872745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:59.545287Z","time spent":"327.412875ms","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T22:25:59.872963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.800414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.23\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-25T22:25:59.873041Z","caller":"traceutil/trace.go:171","msg":"trace[103563110] range","detail":"{range_begin:/registry/masterleases/199.204.45.23; range_end:; response_count:1; response_revision:1485; }","duration":"310.924557ms","start":"2026-06-25T22:25:59.562105Z","end":"2026-06-25T22:25:59.873029Z","steps":["trace[103563110] 'agreement among raft nodes before linearized reading' (duration: 310.734532ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:25:59.873164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:25:59.562084Z","time spent":"311.02452ms","remote":"127.0.0.1:35396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.23\" "} {"level":"info","ts":"2026-06-25T22:26:00.022961Z","caller":"traceutil/trace.go:171","msg":"trace[1832868457] transaction","detail":"{read_only:false; response_revision:1487; number_of_response:1; }","duration":"151.236756ms","start":"2026-06-25T22:25:59.871702Z","end":"2026-06-25T22:26:00.022939Z","steps":["trace[1832868457] 'process raft request' (duration: 151.161644ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:26:00.023434Z","caller":"traceutil/trace.go:171","msg":"trace[1802556288] transaction","detail":"{read_only:false; response_revision:1486; number_of_response:1; }","duration":"152.507487ms","start":"2026-06-25T22:25:59.870909Z","end":"2026-06-25T22:26:00.023417Z","steps":["trace[1802556288] 'process raft request' (duration: 109.03863ms)","trace[1802556288] 'compare' (duration: 42.74228ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:26:00.023852Z","caller":"traceutil/trace.go:171","msg":"trace[1716873184] transaction","detail":"{read_only:false; response_revision:1489; number_of_response:1; }","duration":"152.070396ms","start":"2026-06-25T22:25:59.871767Z","end":"2026-06-25T22:26:00.023837Z","steps":["trace[1716873184] 'process raft request' (duration: 151.513333ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:26:00.024171Z","caller":"traceutil/trace.go:171","msg":"trace[492587791] linearizableReadLoop","detail":"{readStateIndex:1559; appliedIndex:1554; }","duration":"150.140269ms","start":"2026-06-25T22:25:59.87402Z","end":"2026-06-25T22:26:00.02416Z","steps":["trace[492587791] 'read index received' (duration: 105.896343ms)","trace[492587791] 'applied index is now lower than readState.Index' (duration: 44.243146ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:26:00.024256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.227432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:26:00.024289Z","caller":"traceutil/trace.go:171","msg":"trace[525457099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1490; }","duration":"150.442937ms","start":"2026-06-25T22:25:59.873839Z","end":"2026-06-25T22:26:00.024281Z","steps":["trace[525457099] 'agreement among raft nodes before linearized reading' (duration: 150.366795ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:26:00.024475Z","caller":"traceutil/trace.go:171","msg":"trace[1649966572] transaction","detail":"{read_only:false; response_revision:1490; number_of_response:1; }","duration":"152.559578ms","start":"2026-06-25T22:25:59.871906Z","end":"2026-06-25T22:26:00.024466Z","steps":["trace[1649966572] 'process raft request' (duration: 151.883461ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:26:00.023386Z","caller":"traceutil/trace.go:171","msg":"trace[671222891] transaction","detail":"{read_only:false; response_revision:1488; number_of_response:1; }","duration":"151.636796ms","start":"2026-06-25T22:25:59.871723Z","end":"2026-06-25T22:26:00.02336Z","steps":["trace[671222891] 'process raft request' (duration: 151.182555ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:26:02.214105Z","caller":"traceutil/trace.go:171","msg":"trace[1261459305] linearizableReadLoop","detail":"{readStateIndex:1565; appliedIndex:1564; }","duration":"186.504936ms","start":"2026-06-25T22:26:02.027577Z","end":"2026-06-25T22:26:02.214081Z","steps":["trace[1261459305] 'read index received' (duration: 186.314831ms)","trace[1261459305] 'applied index is now lower than readState.Index' (duration: 189.294µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:26:02.214362Z","caller":"traceutil/trace.go:171","msg":"trace[1108090027] transaction","detail":"{read_only:false; response_revision:1495; number_of_response:1; }","duration":"342.343563ms","start":"2026-06-25T22:26:01.872006Z","end":"2026-06-25T22:26:02.214349Z","steps":["trace[1108090027] 'process raft request' (duration: 341.957884ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:26:02.214474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:26:01.871993Z","time spent":"342.406284ms","remote":"127.0.0.1:35620","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-06-25T22:26:02.214779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.194532ms","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-06-25T22:26:02.214829Z","caller":"traceutil/trace.go:171","msg":"trace[215770231] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1495; }","duration":"187.283074ms","start":"2026-06-25T22:26:02.027534Z","end":"2026-06-25T22:26:02.214817Z","steps":["trace[215770231] 'agreement among raft nodes before linearized reading' (duration: 187.148081ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:26:02.215014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.901146ms","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-06-25T22:26:02.215049Z","caller":"traceutil/trace.go:171","msg":"trace[1535235819] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1495; }","duration":"186.963677ms","start":"2026-06-25T22:26:02.028077Z","end":"2026-06-25T22:26:02.215041Z","steps":["trace[1535235819] 'agreement among raft nodes before linearized reading' (duration: 186.872674ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:26:02.215202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.629274ms","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-06-25T22:26:02.215315Z","caller":"traceutil/trace.go:171","msg":"trace[935537975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1495; }","duration":"185.689916ms","start":"2026-06-25T22:26:02.029544Z","end":"2026-06-25T22:26:02.215234Z","steps":["trace[935537975] 'agreement among raft nodes before linearized reading' (duration: 185.600254ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:26:02.215579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.240833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-25T22:26:02.215623Z","caller":"traceutil/trace.go:171","msg":"trace[206220776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1495; }","duration":"187.345266ms","start":"2026-06-25T22:26:02.028265Z","end":"2026-06-25T22:26:02.21561Z","steps":["trace[206220776] 'agreement among raft nodes before linearized reading' (duration: 187.243174ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:26:02.215769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.615233ms","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-06-25T22:26:02.21581Z","caller":"traceutil/trace.go:171","msg":"trace[1466874274] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1495; }","duration":"187.671354ms","start":"2026-06-25T22:26:02.028127Z","end":"2026-06-25T22:26:02.215799Z","steps":["trace[1466874274] 'agreement among raft nodes before linearized reading' (duration: 187.577952ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:26:02.74589Z","caller":"traceutil/trace.go:171","msg":"trace[741194365] transaction","detail":"{read_only:false; response_revision:1496; number_of_response:1; }","duration":"525.759726ms","start":"2026-06-25T22:26:02.220097Z","end":"2026-06-25T22:26:02.745856Z","steps":["trace[741194365] 'process raft request' (duration: 498.492259ms)","trace[741194365] 'compare' (duration: 26.53984ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:26:02.746032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:26:02.220068Z","time spent":"525.89305ms","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T22:26:02.908615Z","caller":"traceutil/trace.go:171","msg":"trace[1543792342] transaction","detail":"{read_only:false; response_revision:1497; number_of_response:1; }","duration":"688.168272ms","start":"2026-06-25T22:26:02.220431Z","end":"2026-06-25T22:26:02.908599Z","steps":["trace[1543792342] 'process raft request' (duration: 644.858617ms)","trace[1543792342] 'compare' (duration: 42.800962ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:26:02.90871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:26:02.220411Z","time spent":"688.250184ms","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T22:26:02.90877Z","caller":"traceutil/trace.go:171","msg":"trace[437576568] transaction","detail":"{read_only:false; response_revision:1500; number_of_response:1; }","duration":"686.742428ms","start":"2026-06-25T22:26:02.221991Z","end":"2026-06-25T22:26:02.908733Z","steps":["trace[437576568] 'process raft request' (duration: 686.383339ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:26:02.908951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:26:02.22198Z","time spent":"686.880081ms","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T22:26:02.909103Z","caller":"traceutil/trace.go:171","msg":"trace[806567779] transaction","detail":"{read_only:false; response_revision:1498; number_of_response:1; }","duration":"688.610133ms","start":"2026-06-25T22:26:02.220487Z","end":"2026-06-25T22:26:02.909097Z","steps":["trace[806567779] 'process raft request' (duration: 687.849174ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:26:02.909143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:26:02.220466Z","time spent":"688.660093ms","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T22:26:02.909202Z","caller":"traceutil/trace.go:171","msg":"trace[770281029] transaction","detail":"{read_only:false; response_revision:1499; number_of_response:1; }","duration":"688.572822ms","start":"2026-06-25T22:26:02.220624Z","end":"2026-06-25T22:26:02.909197Z","steps":["trace[770281029] 'process raft request' (duration: 687.734452ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:26:02.909228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:26:02.22058Z","time spent":"688.635484ms","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T22:26:04.293454Z","caller":"traceutil/trace.go:171","msg":"trace[2017520057] transaction","detail":"{read_only:false; response_revision:1502; number_of_response:1; }","duration":"451.169388ms","start":"2026-06-25T22:26:03.84226Z","end":"2026-06-25T22:26:04.293429Z","steps":["trace[2017520057] 'process raft request' (duration: 451.030465ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:26:04.293693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:26:03.842243Z","time spent":"451.28072ms","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T22:26:04.726386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.54662ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T22:26:04.72648Z","caller":"traceutil/trace.go:171","msg":"trace[1327688166] transaction","detail":"{read_only:false; response_revision:1503; number_of_response:1; }","duration":"505.54695ms","start":"2026-06-25T22:26:04.220915Z","end":"2026-06-25T22:26:04.726462Z","steps":["trace[1327688166] 'process raft request' (duration: 237.829485ms)","trace[1327688166] 'compare' (duration: 267.438028ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:26:04.726536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:26:04.220887Z","time spent":"505.622191ms","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T22:26:04.967423Z","caller":"traceutil/trace.go:171","msg":"trace[670213932] transaction","detail":"{read_only:false; response_revision:1504; number_of_response:1; }","duration":"213.982229ms","start":"2026-06-25T22:26:04.753417Z","end":"2026-06-25T22:26:04.9674Z","steps":["trace[670213932] 'process raft request' (duration: 213.869386ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:26:04.973082Z","caller":"traceutil/trace.go:171","msg":"trace[1557960458] linearizableReadLoop","detail":"{readStateIndex:1575; appliedIndex:1573; }","duration":"109.158812ms","start":"2026-06-25T22:26:04.863897Z","end":"2026-06-25T22:26:04.973056Z","steps":["trace[1557960458] 'read index received' (duration: 103.483145ms)","trace[1557960458] 'applied index is now lower than readState.Index' (duration: 5.674127ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:26:04.973371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.463859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:26:04.973419Z","caller":"traceutil/trace.go:171","msg":"trace[1124037408] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1504; }","duration":"109.558052ms","start":"2026-06-25T22:26:04.863848Z","end":"2026-06-25T22:26:04.973406Z","steps":["trace[1124037408] 'agreement among raft nodes before linearized reading' (duration: 109.326576ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:26:09.987765Z","caller":"traceutil/trace.go:171","msg":"trace[2092317847] transaction","detail":"{read_only:false; response_revision:1551; number_of_response:1; }","duration":"107.550533ms","start":"2026-06-25T22:26:09.88019Z","end":"2026-06-25T22:26:09.98774Z","steps":["trace[2092317847] 'process raft request' (duration: 107.238906ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:26:10.851067Z","caller":"traceutil/trace.go:171","msg":"trace[1520005522] transaction","detail":"{read_only:false; response_revision:1552; number_of_response:1; }","duration":"107.773389ms","start":"2026-06-25T22:26:10.743273Z","end":"2026-06-25T22:26:10.851046Z","steps":["trace[1520005522] 'process raft request' (duration: 107.649576ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:26:11.20404Z","caller":"traceutil/trace.go:171","msg":"trace[987498376] transaction","detail":"{read_only:false; response_revision:1555; number_of_response:1; }","duration":"190.232996ms","start":"2026-06-25T22:26:11.013773Z","end":"2026-06-25T22:26:11.204006Z","steps":["trace[987498376] 'process raft request' (duration: 189.99137ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:26:11.204062Z","caller":"traceutil/trace.go:171","msg":"trace[1303451862] transaction","detail":"{read_only:false; response_revision:1556; number_of_response:1; }","duration":"189.94577ms","start":"2026-06-25T22:26:11.01409Z","end":"2026-06-25T22:26:11.204035Z","steps":["trace[1303451862] 'process raft request' (duration: 189.784366ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:26:11.204214Z","caller":"traceutil/trace.go:171","msg":"trace[1840980112] transaction","detail":"{read_only:false; response_revision:1557; number_of_response:1; }","duration":"189.95653ms","start":"2026-06-25T22:26:11.014132Z","end":"2026-06-25T22:26:11.204089Z","steps":["trace[1840980112] 'process raft request' (duration: 189.794566ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:26:12.37725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.07539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:26:12.37734Z","caller":"traceutil/trace.go:171","msg":"trace[1846912741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1557; }","duration":"513.204793ms","start":"2026-06-25T22:26:11.864108Z","end":"2026-06-25T22:26:12.377312Z","steps":["trace[1846912741] 'range keys from in-memory index tree' (duration: 512.942557ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:26:12.377378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:26:11.864089Z","time spent":"513.280456ms","remote":"127.0.0.1:35352","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-06-25T22:26:12.377658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:26:11.445652Z","time spent":"932.00207ms","remote":"127.0.0.1:34656","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-25T22:26:12.377713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.339013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:26:12.377758Z","caller":"traceutil/trace.go:171","msg":"trace[1417268343] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1557; }","duration":"400.424415ms","start":"2026-06-25T22:26:11.977323Z","end":"2026-06-25T22:26:12.377748Z","steps":["trace[1417268343] 'count revisions from in-memory index tree' (duration: 400.225139ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:26:12.377795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:26:11.977303Z","time spent":"400.480246ms","remote":"127.0.0.1:53538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-25T22:26:12.377746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.523155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-25T22:26:12.377975Z","caller":"traceutil/trace.go:171","msg":"trace[1081674264] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1557; }","duration":"655.800122ms","start":"2026-06-25T22:26:11.722164Z","end":"2026-06-25T22:26:12.377964Z","steps":["trace[1081674264] 'count revisions from in-memory index tree' (duration: 655.453493ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:26:12.37802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:26:11.722144Z","time spent":"655.857203ms","remote":"127.0.0.1:39140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-06-25T22:26:13.49433Z","caller":"traceutil/trace.go:171","msg":"trace[391294354] linearizableReadLoop","detail":"{readStateIndex:1631; appliedIndex:1630; }","duration":"480.933856ms","start":"2026-06-25T22:26:13.013359Z","end":"2026-06-25T22:26:13.494293Z","steps":["trace[391294354] 'read index received' (duration: 465.500174ms)","trace[391294354] 'applied index is now lower than readState.Index' (duration: 15.432412ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:26:13.494479Z","caller":"traceutil/trace.go:171","msg":"trace[505544480] transaction","detail":"{read_only:false; response_revision:1559; number_of_response:1; }","duration":"638.628157ms","start":"2026-06-25T22:26:12.855834Z","end":"2026-06-25T22:26:13.494462Z","steps":["trace[505544480] 'process raft request' (duration: 623.01465ms)","trace[505544480] 'compare' (duration: 15.31852ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:26:13.4946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:26:12.855814Z","time spent":"638.697588ms","remote":"127.0.0.1:35620","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-06-25T22:26:13.494649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.14482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-06-25T22:26:13.494714Z","caller":"traceutil/trace.go:171","msg":"trace[290039012] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1559; }","duration":"425.251783ms","start":"2026-06-25T22:26:13.069446Z","end":"2026-06-25T22:26:13.494698Z","steps":["trace[290039012] 'agreement among raft nodes before linearized reading' (duration: 425.078359ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:26:13.494752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:26:13.069428Z","time spent":"425.315615ms","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-25T22:26:13.49489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.54014ms","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-06-25T22:26:13.494919Z","caller":"traceutil/trace.go:171","msg":"trace[216875866] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1559; }","duration":"481.602202ms","start":"2026-06-25T22:26:13.013304Z","end":"2026-06-25T22:26:13.494907Z","steps":["trace[216875866] 'agreement among raft nodes before linearized reading' (duration: 481.52724ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:26:13.494944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:26:13.013275Z","time spent":"481.663374ms","remote":"127.0.0.1:35620","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-06-25T22:26:13.495048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.598646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-25T22:26:13.495091Z","caller":"traceutil/trace.go:171","msg":"trace[1794602481] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1559; }","duration":"285.665287ms","start":"2026-06-25T22:26:13.209418Z","end":"2026-06-25T22:26:13.495083Z","steps":["trace[1794602481] 'agreement among raft nodes before linearized reading' (duration: 285.578855ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:26:13.495312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.924052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-06-25T22:26:13.495372Z","caller":"traceutil/trace.go:171","msg":"trace[62394277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1559; }","duration":"287.982954ms","start":"2026-06-25T22:26:13.207374Z","end":"2026-06-25T22:26:13.495357Z","steps":["trace[62394277] 'agreement among raft nodes before linearized reading' (duration: 287.794929ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:26:13.495358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.815329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-25T22:26:13.495425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.662977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:26:13.495435Z","caller":"traceutil/trace.go:171","msg":"trace[1816695675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1559; }","duration":"287.939322ms","start":"2026-06-25T22:26:13.207472Z","end":"2026-06-25T22:26:13.495412Z","steps":["trace[1816695675] 'agreement among raft nodes before linearized reading' (duration: 287.719717ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:26:13.49547Z","caller":"traceutil/trace.go:171","msg":"trace[641571537] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1559; }","duration":"297.724848ms","start":"2026-06-25T22:26:13.197731Z","end":"2026-06-25T22:26:13.495456Z","steps":["trace[641571537] 'agreement among raft nodes before linearized reading' (duration: 297.664037ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:26:13.495375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.193782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:26:13.495524Z","caller":"traceutil/trace.go:171","msg":"trace[935106092] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1559; }","duration":"335.345285ms","start":"2026-06-25T22:26:13.160165Z","end":"2026-06-25T22:26:13.49551Z","steps":["trace[935106092] 'agreement among raft nodes before linearized reading' (duration: 335.176871ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:26:13.495753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:26:13.160125Z","time spent":"335.428317ms","remote":"127.0.0.1:39124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-06-25T22:26:44.378481Z","caller":"traceutil/trace.go:171","msg":"trace[1440494151] transaction","detail":"{read_only:false; response_revision:1726; number_of_response:1; }","duration":"171.71774ms","start":"2026-06-25T22:26:44.206739Z","end":"2026-06-25T22:26:44.378457Z","steps":["trace[1440494151] 'process raft request' (duration: 171.575966ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:27:54.244152Z","caller":"traceutil/trace.go:171","msg":"trace[2137004821] linearizableReadLoop","detail":"{readStateIndex:2138; appliedIndex:2136; }","duration":"107.888099ms","start":"2026-06-25T22:27:54.136242Z","end":"2026-06-25T22:27:54.24413Z","steps":["trace[2137004821] 'read index received' (duration: 43.431661ms)","trace[2137004821] 'applied index is now lower than readState.Index' (duration: 64.455478ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:27:54.244267Z","caller":"traceutil/trace.go:171","msg":"trace[1549058204] transaction","detail":"{read_only:false; response_revision:2046; number_of_response:1; }","duration":"145.536499ms","start":"2026-06-25T22:27:54.098703Z","end":"2026-06-25T22:27:54.24424Z","steps":["trace[1549058204] 'process raft request' (duration: 145.282183ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:27:54.244437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.142066ms","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-06-25T22:27:54.244454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.161076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-25T22:27:54.244497Z","caller":"traceutil/trace.go:171","msg":"trace[693037493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2046; }","duration":"108.253758ms","start":"2026-06-25T22:27:54.136227Z","end":"2026-06-25T22:27:54.244481Z","steps":["trace[693037493] 'agreement among raft nodes before linearized reading' (duration: 108.080464ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:27:54.244483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.955128ms","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-06-25T22:27:54.244503Z","caller":"traceutil/trace.go:171","msg":"trace[1854083586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2046; }","duration":"108.279899ms","start":"2026-06-25T22:27:54.136209Z","end":"2026-06-25T22:27:54.244489Z","steps":["trace[1854083586] 'agreement among raft nodes before linearized reading' (duration: 108.070574ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:27:54.244542Z","caller":"traceutil/trace.go:171","msg":"trace[305612382] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2046; }","duration":"105.03487ms","start":"2026-06-25T22:27:54.139494Z","end":"2026-06-25T22:27:54.244529Z","steps":["trace[305612382] 'agreement among raft nodes before linearized reading' (duration: 104.830715ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:28:13.271865Z","caller":"traceutil/trace.go:171","msg":"trace[1669755265] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"115.35061ms","start":"2026-06-25T22:28:13.156489Z","end":"2026-06-25T22:28:13.27184Z","steps":["trace[1669755265] 'process raft request' (duration: 115.182496ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:30:12.960182Z","caller":"traceutil/trace.go:171","msg":"trace[1156760900] transaction","detail":"{read_only:false; response_revision:2707; number_of_response:1; }","duration":"154.064994ms","start":"2026-06-25T22:30:12.806075Z","end":"2026-06-25T22:30:12.96014Z","steps":["trace[1156760900] 'process raft request' (duration: 153.860779ms)"],"step_count":1}