{"level":"warn","ts":"2026-05-13T22:03:11.95827Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-13T22:03:11.958352Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.229: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.229:2380","--initial-cluster=instance=https://199.204.45.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.229:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-13T22:03:11.958417Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-13T22:03:11.958424Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-05-13T22:03:11.95844Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-13T22:03:11.959255Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"]} {"level":"info","ts":"2026-05-13T22:03:11.959368Z","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.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.229:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-13T22:03:11.963788Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.250936ms"} {"level":"info","ts":"2026-05-13T22:03:11.970753Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3ba9ae53cc4335db","cluster-id":"4ad2674607ff1d60"} {"level":"info","ts":"2026-05-13T22:03:11.97087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=()"} {"level":"info","ts":"2026-05-13T22:03:11.970902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 0"} {"level":"info","ts":"2026-05-13T22:03:11.970915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3ba9ae53cc4335db [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-13T22:03:11.970928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 1"} {"level":"info","ts":"2026-05-13T22:03:11.970968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"warn","ts":"2026-05-13T22:03:11.976276Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-13T22:03:11.977985Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-13T22:03:11.979215Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-13T22:03:11.982691Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3ba9ae53cc4335db","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-13T22:03:11.983178Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3ba9ae53cc4335db","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-13T22:03:11.983206Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-13T22:03:11.983307Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-13T22:03:11.983322Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-13T22:03:11.986981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"info","ts":"2026-05-13T22:03:11.987177Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","added-peer-id":"3ba9ae53cc4335db","added-peer-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-05-13T22:03:11.98949Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-13T22:03:11.98965Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3ba9ae53cc4335db","initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-13T22:03:11.989667Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-13T22:03:11.989752Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-05-13T22:03:11.989757Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-05-13T22:03:12.971658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db is starting a new election at term 1"} {"level":"info","ts":"2026-05-13T22:03:12.971739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became pre-candidate at term 1"} {"level":"info","ts":"2026-05-13T22:03:12.971769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgPreVoteResp from 3ba9ae53cc4335db at term 1"} {"level":"info","ts":"2026-05-13T22:03:12.971804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became candidate at term 2"} {"level":"info","ts":"2026-05-13T22:03:12.971821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgVoteResp from 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-05-13T22:03:12.971841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became leader at term 2"} {"level":"info","ts":"2026-05-13T22:03:12.971859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3ba9ae53cc4335db elected leader 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-05-13T22:03:12.972937Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-13T22:03:12.973616Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3ba9ae53cc4335db","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.229:2379]}","request-path":"/0/members/3ba9ae53cc4335db/attributes","cluster-id":"4ad2674607ff1d60","publish-timeout":"7s"} {"level":"info","ts":"2026-05-13T22:03:12.973627Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-13T22:03:12.973828Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-13T22:03:12.974088Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","cluster-version":"3.5"} {"level":"info","ts":"2026-05-13T22:03:12.974143Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-13T22:03:12.974223Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-13T22:03:12.974267Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-13T22:03:12.974226Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-13T22:03:12.977244Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.229:2379"} {"level":"info","ts":"2026-05-13T22:03:12.977701Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-13T22:03:37.061041Z","caller":"traceutil/trace.go:171","msg":"trace[1409863692] linearizableReadLoop","detail":"{readStateIndex:438; appliedIndex:437; }","duration":"247.516555ms","start":"2026-05-13T22:03:36.813507Z","end":"2026-05-13T22:03:37.061024Z","steps":["trace[1409863692] 'read index received' (duration: 247.377442ms)","trace[1409863692] 'applied index is now lower than readState.Index' (duration: 138.433µs)"],"step_count":2} {"level":"info","ts":"2026-05-13T22:03:37.061139Z","caller":"traceutil/trace.go:171","msg":"trace[1642751042] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"363.105069ms","start":"2026-05-13T22:03:36.697992Z","end":"2026-05-13T22:03:37.061098Z","steps":["trace[1642751042] 'process raft request' (duration: 362.907554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:37.061188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.667898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-13T22:03:37.061231Z","caller":"traceutil/trace.go:171","msg":"trace[1346989752] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:421; }","duration":"247.759201ms","start":"2026-05-13T22:03:36.813466Z","end":"2026-05-13T22:03:37.061225Z","steps":["trace[1346989752] 'agreement among raft nodes before linearized reading' (duration: 247.643118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:37.061902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:36.697976Z","time spent":"363.261973ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T22:03:37.853522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.398749ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-13T22:03:37.853643Z","caller":"traceutil/trace.go:171","msg":"trace[1633759833] linearizableReadLoop","detail":"{readStateIndex:439; appliedIndex:438; }","duration":"690.043947ms","start":"2026-05-13T22:03:37.16357Z","end":"2026-05-13T22:03:37.853614Z","steps":["trace[1633759833] 'read index received' (duration: 154.275939ms)","trace[1633759833] 'applied index is now lower than readState.Index' (duration: 535.766878ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T22:03:37.853667Z","caller":"traceutil/trace.go:171","msg":"trace[1079976057] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"788.952095ms","start":"2026-05-13T22:03:37.06469Z","end":"2026-05-13T22:03:37.853642Z","steps":["trace[1079976057] 'process raft request' (duration: 253.201557ms)","trace[1079976057] 'compare' (duration: 535.283976ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T22:03:37.853706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.140629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:03:37.853732Z","caller":"traceutil/trace.go:171","msg":"trace[1405231531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:422; }","duration":"690.195151ms","start":"2026-05-13T22:03:37.163528Z","end":"2026-05-13T22:03:37.853723Z","steps":["trace[1405231531] 'agreement among raft nodes before linearized reading' (duration: 690.148589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:37.853773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:37.163512Z","time spent":"690.254192ms","remote":"127.0.0.1:52244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-13T22:03:37.853778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:37.064677Z","time spent":"789.043436ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T22:03:38.112062Z","caller":"traceutil/trace.go:171","msg":"trace[39067985] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:439; }","duration":"113.245615ms","start":"2026-05-13T22:03:37.99879Z","end":"2026-05-13T22:03:38.112036Z","steps":["trace[39067985] 'read index received' (duration: 110.362083ms)","trace[39067985] 'applied index is now lower than readState.Index' (duration: 2.882512ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T22:03:38.112176Z","caller":"traceutil/trace.go:171","msg":"trace[1221138512] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"159.90551ms","start":"2026-05-13T22:03:37.952236Z","end":"2026-05-13T22:03:38.112142Z","steps":["trace[1221138512] 'process raft request' (duration: 156.930766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:38.112249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.44091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-13T22:03:38.112283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.692407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-13T22:03:38.11232Z","caller":"traceutil/trace.go:171","msg":"trace[1367875319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:423; }","duration":"113.566043ms","start":"2026-05-13T22:03:37.99874Z","end":"2026-05-13T22:03:38.112306Z","steps":["trace[1367875319] 'agreement among raft nodes before linearized reading' (duration: 113.417279ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:03:38.112325Z","caller":"traceutil/trace.go:171","msg":"trace[1964723794] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:423; }","duration":"111.798979ms","start":"2026-05-13T22:03:38.000515Z","end":"2026-05-13T22:03:38.112314Z","steps":["trace[1964723794] 'agreement among raft nodes before linearized reading' (duration: 111.698867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:44.616081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.328481226s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-13T22:03:44.616231Z","caller":"traceutil/trace.go:171","msg":"trace[430003068] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.522277631s","start":"2026-05-13T22:03:43.093927Z","end":"2026-05-13T22:03:44.616205Z","steps":["trace[430003068] 'process raft request' (duration: 193.550399ms)","trace[430003068] 'compare' (duration: 1.328321972s)"],"step_count":2} {"level":"warn","ts":"2026-05-13T22:03:44.616298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:43.093913Z","time spent":"1.522356853s","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T22:03:44.627715Z","caller":"traceutil/trace.go:171","msg":"trace[510052647] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.489672037s","start":"2026-05-13T22:03:43.138008Z","end":"2026-05-13T22:03:44.62768Z","steps":["trace[510052647] 'process raft request' (duration: 1.488215082s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:44.627903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:43.137989Z","time spent":"1.489816382s","remote":"127.0.0.1:52308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T22:03:44.628036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.460853848s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:03:44.628092Z","caller":"traceutil/trace.go:171","msg":"trace[902330615] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:541; }","duration":"1.46092572s","start":"2026-05-13T22:03:43.167136Z","end":"2026-05-13T22:03:44.628062Z","steps":["trace[902330615] 'agreement among raft nodes before linearized reading' (duration: 1.460805677s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:44.628124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:43.167118Z","time spent":"1.460999942s","remote":"127.0.0.1:52246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-13T22:03:44.627896Z","caller":"traceutil/trace.go:171","msg":"trace[2045920516] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:558; }","duration":"1.460638503s","start":"2026-05-13T22:03:43.167181Z","end":"2026-05-13T22:03:44.627819Z","steps":["trace[2045920516] 'read index received' (duration: 120.376603ms)","trace[2045920516] 'applied index is now lower than readState.Index' (duration: 1.34026074s)"],"step_count":2} {"level":"info","ts":"2026-05-13T22:03:44.62831Z","caller":"traceutil/trace.go:171","msg":"trace[1164828059] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.451935796s","start":"2026-05-13T22:03:43.176363Z","end":"2026-05-13T22:03:44.628299Z","steps":["trace[1164828059] 'process raft request' (duration: 1.451244189s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:44.628421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:43.176341Z","time spent":"1.452023419s","remote":"127.0.0.1:52308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-13T22:03:44.628765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.727171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-13T22:03:44.628809Z","caller":"traceutil/trace.go:171","msg":"trace[572546230] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:541; }","duration":"752.771842ms","start":"2026-05-13T22:03:43.876028Z","end":"2026-05-13T22:03:44.6288Z","steps":["trace[572546230] 'agreement among raft nodes before linearized reading' (duration: 752.68272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:44.628849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:43.875936Z","time spent":"752.906715ms","remote":"127.0.0.1:52556","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-05-13T22:03:44.62897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.94705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-13T22:03:44.629027Z","caller":"traceutil/trace.go:171","msg":"trace[1844556357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:541; }","duration":"390.040991ms","start":"2026-05-13T22:03:44.238968Z","end":"2026-05-13T22:03:44.629009Z","steps":["trace[1844556357] 'agreement among raft nodes before linearized reading' (duration: 389.840706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:44.629075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:44.238946Z","time spent":"390.120345ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-13T22:03:44.629139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.440554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-13T22:03:44.629264Z","caller":"traceutil/trace.go:171","msg":"trace[1978433222] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:541; }","duration":"457.604898ms","start":"2026-05-13T22:03:44.171611Z","end":"2026-05-13T22:03:44.629216Z","steps":["trace[1978433222] 'agreement among raft nodes before linearized reading' (duration: 457.27142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:44.629332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:44.171583Z","time spent":"457.7172ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-13T22:03:46.085665Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.332351053s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-13T22:03:46.091049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.337667956s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-13T22:03:46.09145Z","caller":"traceutil/trace.go:171","msg":"trace[2062449503] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"1.455086216s","start":"2026-05-13T22:03:44.636339Z","end":"2026-05-13T22:03:46.091425Z","steps":["trace[2062449503] 'process raft request' (duration: 1.454889611s)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:03:46.091497Z","caller":"traceutil/trace.go:171","msg":"trace[1949628920] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"1.456792847s","start":"2026-05-13T22:03:44.634682Z","end":"2026-05-13T22:03:46.091475Z","steps":["trace[1949628920] 'process raft request' (duration: 118.601739ms)","trace[1949628920] 'compare' (duration: 1.337256254s)"],"step_count":2} {"level":"warn","ts":"2026-05-13T22:03:46.092118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:44.634661Z","time spent":"1.457377912s","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T22:03:46.091559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:44.636323Z","time spent":"1.455187478s","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T22:03:46.091606Z","caller":"traceutil/trace.go:171","msg":"trace[1138710940] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"1.456840138s","start":"2026-05-13T22:03:44.634754Z","end":"2026-05-13T22:03:46.091595Z","steps":["trace[1138710940] 'process raft request' (duration: 1.456397777s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:46.092473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:44.634725Z","time spent":"1.457694039s","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T22:03:46.09167Z","caller":"traceutil/trace.go:171","msg":"trace[929500711] linearizableReadLoop","detail":"{readStateIndex:563; appliedIndex:561; }","duration":"1.455787353s","start":"2026-05-13T22:03:44.63585Z","end":"2026-05-13T22:03:46.091638Z","steps":["trace[929500711] 'read index received' (duration: 117.448891ms)","trace[929500711] 'applied index is now lower than readState.Index' (duration: 1.338337102s)"],"step_count":2} {"level":"warn","ts":"2026-05-13T22:03:46.091787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.455916036s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:03:46.092691Z","caller":"traceutil/trace.go:171","msg":"trace[247753734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:545; }","duration":"1.456848409s","start":"2026-05-13T22:03:44.635804Z","end":"2026-05-13T22:03:46.092653Z","steps":["trace[247753734] 'agreement among raft nodes before linearized reading' (duration: 1.455921346s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:46.092766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:44.635788Z","time spent":"1.456958342s","remote":"127.0.0.1:52244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-13T22:03:46.09179Z","caller":"traceutil/trace.go:171","msg":"trace[242878538] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"1.412041462s","start":"2026-05-13T22:03:44.679727Z","end":"2026-05-13T22:03:46.091769Z","steps":["trace[242878538] 'process raft request' (duration: 1.41157683s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:46.092862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:44.679698Z","time spent":"1.413114117s","remote":"127.0.0.1:52308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15664,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T22:03:46.105732Z","caller":"traceutil/trace.go:171","msg":"trace[1456447863] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"1.305497192s","start":"2026-05-13T22:03:44.800216Z","end":"2026-05-13T22:03:46.105713Z","steps":["trace[1456447863] 'process raft request' (duration: 1.305318808s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:46.105839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:44.800198Z","time spent":"1.305583704s","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T22:03:46.106474Z","caller":"traceutil/trace.go:171","msg":"trace[1603899418] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"1.259548836s","start":"2026-05-13T22:03:44.84691Z","end":"2026-05-13T22:03:46.106459Z","steps":["trace[1603899418] 'process raft request' (duration: 1.25888882s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:46.106513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.436789129s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-05-13T22:03:46.106638Z","caller":"traceutil/trace.go:171","msg":"trace[1886196129] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:547; }","duration":"1.436948913s","start":"2026-05-13T22:03:44.669672Z","end":"2026-05-13T22:03:46.106621Z","steps":["trace[1886196129] 'agreement among raft nodes before linearized reading' (duration: 1.436702167s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:46.106711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:44.669651Z","time spent":"1.437047035s","remote":"127.0.0.1:52308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":29592,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-05-13T22:03:46.106648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.063185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.229\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-13T22:03:46.106804Z","caller":"traceutil/trace.go:171","msg":"trace[6160180] range","detail":"{range_begin:/registry/masterleases/199.204.45.229; range_end:; response_count:1; response_revision:547; }","duration":"116.25328ms","start":"2026-05-13T22:03:45.990537Z","end":"2026-05-13T22:03:46.10679Z","steps":["trace[6160180] 'agreement among raft nodes before linearized reading' (duration: 116.076416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:46.10658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:44.846876Z","time spent":"1.259663979s","remote":"127.0.0.1:52308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T22:03:47.875808Z","caller":"traceutil/trace.go:171","msg":"trace[611596395] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"235.950567ms","start":"2026-05-13T22:03:47.639834Z","end":"2026-05-13T22:03:47.875784Z","steps":["trace[611596395] 'process raft request' (duration: 235.824873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:53.407943Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880869378639849477,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-13T22:03:53.698697Z","caller":"traceutil/trace.go:171","msg":"trace[594949084] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"791.418906ms","start":"2026-05-13T22:03:52.907251Z","end":"2026-05-13T22:03:53.69867Z","steps":["trace[594949084] 'read index received' (duration: 791.094448ms)","trace[594949084] 'applied index is now lower than readState.Index' (duration: 323.568µs)"],"step_count":2} {"level":"warn","ts":"2026-05-13T22:03:53.698869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.59898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:03:53.698907Z","caller":"traceutil/trace.go:171","msg":"trace[1675233715] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:592; }","duration":"791.691212ms","start":"2026-05-13T22:03:52.907205Z","end":"2026-05-13T22:03:53.698896Z","steps":["trace[1675233715] 'agreement among raft nodes before linearized reading' (duration: 791.60716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:53.699114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:52.907182Z","time spent":"791.914237ms","remote":"127.0.0.1:40080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 "} {"level":"warn","ts":"2026-05-13T22:03:53.699244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.947679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:03:53.699325Z","caller":"traceutil/trace.go:171","msg":"trace[330422473] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:592; }","duration":"424.02425ms","start":"2026-05-13T22:03:53.275282Z","end":"2026-05-13T22:03:53.699306Z","steps":["trace[330422473] 'agreement among raft nodes before linearized reading' (duration: 423.926998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:53.699404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.535082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:03:53.699463Z","caller":"traceutil/trace.go:171","msg":"trace[2062522261] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:592; }","duration":"226.610514ms","start":"2026-05-13T22:03:53.472834Z","end":"2026-05-13T22:03:53.699445Z","steps":["trace[2062522261] 'agreement among raft nodes before linearized reading' (duration: 226.509771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:53.699478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:53.275224Z","time spent":"424.205184ms","remote":"127.0.0.1:40134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"info","ts":"2026-05-13T22:03:53.69958Z","caller":"traceutil/trace.go:171","msg":"trace[1472760601] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"886.352494ms","start":"2026-05-13T22:03:52.812382Z","end":"2026-05-13T22:03:53.698735Z","steps":["trace[1472760601] 'process raft request' (duration: 886.099408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:53.700212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:52.812339Z","time spent":"887.810081ms","remote":"127.0.0.1:52454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T22:03:53.699108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.777683ms","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-05-13T22:03:53.700332Z","caller":"traceutil/trace.go:171","msg":"trace[860182110] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:592; }","duration":"425.021645ms","start":"2026-05-13T22:03:53.275271Z","end":"2026-05-13T22:03:53.700293Z","steps":["trace[860182110] 'agreement among raft nodes before linearized reading' (duration: 423.770673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:53.700389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:53.275252Z","time spent":"425.121558ms","remote":"127.0.0.1:40134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-13T22:03:53.699691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.748041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:03:53.700464Z","caller":"traceutil/trace.go:171","msg":"trace[871526580] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:592; }","duration":"775.53504ms","start":"2026-05-13T22:03:52.924906Z","end":"2026-05-13T22:03:53.700441Z","steps":["trace[871526580] 'agreement among raft nodes before linearized reading' (duration: 774.70771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:53.699686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.510345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:03:53.700535Z","caller":"traceutil/trace.go:171","msg":"trace[1506141986] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:592; }","duration":"563.394486ms","start":"2026-05-13T22:03:53.137121Z","end":"2026-05-13T22:03:53.700515Z","steps":["trace[1506141986] 'agreement among raft nodes before linearized reading' (duration: 562.450193ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:53.700542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:52.924865Z","time spent":"775.658043ms","remote":"127.0.0.1:40090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"warn","ts":"2026-05-13T22:03:53.700596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:53.137115Z","time spent":"563.466419ms","remote":"127.0.0.1:40132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-13T22:03:53.699749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.677546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:03:53.700763Z","caller":"traceutil/trace.go:171","msg":"trace[714753173] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:592; }","duration":"726.705351ms","start":"2026-05-13T22:03:52.974047Z","end":"2026-05-13T22:03:53.700753Z","steps":["trace[714753173] 'agreement among raft nodes before linearized reading' (duration: 725.681406ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:53.700802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:52.974008Z","time spent":"726.783633ms","remote":"127.0.0.1:40104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 "} {"level":"warn","ts":"2026-05-13T22:03:53.699783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.714341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:03:53.701174Z","caller":"traceutil/trace.go:171","msg":"trace[159329851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"534.127556ms","start":"2026-05-13T22:03:53.167028Z","end":"2026-05-13T22:03:53.701155Z","steps":["trace[159329851] 'agreement among raft nodes before linearized reading' (duration: 532.726481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:53.70123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:53.167004Z","time spent":"534.212589ms","remote":"127.0.0.1:52246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-13T22:03:53.699783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.118396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:03:53.701444Z","caller":"traceutil/trace.go:171","msg":"trace[663181968] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:592; }","duration":"228.786608ms","start":"2026-05-13T22:03:53.472638Z","end":"2026-05-13T22:03:53.701425Z","steps":["trace[663181968] 'agreement among raft nodes before linearized reading' (duration: 227.114046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:53.699098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.791735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:03:53.701628Z","caller":"traceutil/trace.go:171","msg":"trace[1173824222] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:592; }","duration":"794.393149ms","start":"2026-05-13T22:03:52.907222Z","end":"2026-05-13T22:03:53.701615Z","steps":["trace[1173824222] 'agreement among raft nodes before linearized reading' (duration: 791.776024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:53.701679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:52.907204Z","time spent":"794.460022ms","remote":"127.0.0.1:40080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-05-13T22:03:53.69978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.761965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:03:53.701878Z","caller":"traceutil/trace.go:171","msg":"trace[1477135787] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:592; }","duration":"777.878127ms","start":"2026-05-13T22:03:52.923982Z","end":"2026-05-13T22:03:53.70186Z","steps":["trace[1477135787] 'agreement among raft nodes before linearized reading' (duration: 775.697034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:53.70194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:52.923976Z","time spent":"777.94545ms","remote":"127.0.0.1:40090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"warn","ts":"2026-05-13T22:03:53.699787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.462156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:03:53.702101Z","caller":"traceutil/trace.go:171","msg":"trace[1169379223] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:592; }","duration":"728.796425ms","start":"2026-05-13T22:03:52.973287Z","end":"2026-05-13T22:03:53.702084Z","steps":["trace[1169379223] 'agreement among raft nodes before linearized reading' (duration: 726.481027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:53.702185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:52.973272Z","time spent":"728.890277ms","remote":"127.0.0.1:40104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-13T22:03:53.699828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.210589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-13T22:03:53.699827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.887339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:03:53.702621Z","caller":"traceutil/trace.go:171","msg":"trace[682225627] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:592; }","duration":"229.861765ms","start":"2026-05-13T22:03:53.472589Z","end":"2026-05-13T22:03:53.702451Z","steps":["trace[682225627] 'agreement among raft nodes before linearized reading' (duration: 227.219059ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:03:53.702684Z","caller":"traceutil/trace.go:171","msg":"trace[1682922069] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:592; }","duration":"564.737181ms","start":"2026-05-13T22:03:53.13792Z","end":"2026-05-13T22:03:53.702657Z","steps":["trace[1682922069] 'agreement among raft nodes before linearized reading' (duration: 561.88666ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:53.702774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:53.137916Z","time spent":"564.836843ms","remote":"127.0.0.1:40132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-13T22:03:53.699835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.022101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:03:53.702958Z","caller":"traceutil/trace.go:171","msg":"trace[1474594152] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:592; }","duration":"795.148759ms","start":"2026-05-13T22:03:52.907796Z","end":"2026-05-13T22:03:53.702945Z","steps":["trace[1474594152] 'agreement among raft nodes before linearized reading' (duration: 792.021841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:53.702996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:52.90779Z","time spent":"795.193381ms","remote":"127.0.0.1:40080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 "} {"level":"warn","ts":"2026-05-13T22:03:53.699836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.669213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:03:53.703191Z","caller":"traceutil/trace.go:171","msg":"trace[1617570064] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:592; }","duration":"779.027587ms","start":"2026-05-13T22:03:52.924148Z","end":"2026-05-13T22:03:53.703176Z","steps":["trace[1617570064] 'agreement among raft nodes before linearized reading' (duration: 775.665103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:53.703243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:52.924142Z","time spent":"779.083998ms","remote":"127.0.0.1:40090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-05-13T22:03:53.699867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.290216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:03:53.703474Z","caller":"traceutil/trace.go:171","msg":"trace[7935855] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:592; }","duration":"245.891066ms","start":"2026-05-13T22:03:53.457556Z","end":"2026-05-13T22:03:53.703447Z","steps":["trace[7935855] 'agreement among raft nodes before linearized reading' (duration: 242.289476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:53.699829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.765914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:03:53.703551Z","caller":"traceutil/trace.go:171","msg":"trace[1717215583] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:592; }","duration":"603.537289ms","start":"2026-05-13T22:03:53.099992Z","end":"2026-05-13T22:03:53.70353Z","steps":["trace[1717215583] 'agreement among raft nodes before linearized reading' (duration: 599.654332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:53.699883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.340139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-13T22:03:53.699886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.817411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-13T22:03:53.699887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.068959ms","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:5"} {"level":"warn","ts":"2026-05-13T22:03:53.69991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.392231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-13T22:03:53.699931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.179181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-13T22:03:53.699923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.057074ms","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:5"} {"level":"warn","ts":"2026-05-13T22:03:53.699947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.705143ms","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":"warn","ts":"2026-05-13T22:03:53.699942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.832936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-13T22:03:53.69997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.9547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-13T22:03:53.699966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.105883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-13T22:03:53.699958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.254729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-13T22:03:53.699951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.501747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-13T22:03:53.699927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.861734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-13T22:03:53.700002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.826826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:03:53.707907Z","caller":"traceutil/trace.go:171","msg":"trace[281393177] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:592; }","duration":"304.719163ms","start":"2026-05-13T22:03:53.40316Z","end":"2026-05-13T22:03:53.707879Z","steps":["trace[281393177] 'agreement among raft nodes before linearized reading' (duration: 296.828036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:53.707969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:53.403155Z","time spent":"304.792755ms","remote":"127.0.0.1:40134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 "} {"level":"info","ts":"2026-05-13T22:03:53.707996Z","caller":"traceutil/trace.go:171","msg":"trace[289245191] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:592; }","duration":"251.117535ms","start":"2026-05-13T22:03:53.456843Z","end":"2026-05-13T22:03:53.707961Z","steps":["trace[289245191] 'agreement among raft nodes before linearized reading' (duration: 243.062234ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:03:53.708087Z","caller":"traceutil/trace.go:171","msg":"trace[1195696414] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:592; }","duration":"287.233747ms","start":"2026-05-13T22:03:53.420838Z","end":"2026-05-13T22:03:53.708071Z","steps":["trace[1195696414] 'agreement among raft nodes before linearized reading' (duration: 279.110424ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:03:53.70814Z","caller":"traceutil/trace.go:171","msg":"trace[1003989709] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:592; }","duration":"251.467134ms","start":"2026-05-13T22:03:53.456659Z","end":"2026-05-13T22:03:53.708126Z","steps":["trace[1003989709] 'agreement among raft nodes before linearized reading' (duration: 243.258729ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:03:53.708231Z","caller":"traceutil/trace.go:171","msg":"trace[2052542387] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:592; }","duration":"270.417968ms","start":"2026-05-13T22:03:53.437798Z","end":"2026-05-13T22:03:53.708216Z","steps":["trace[2052542387] 'agreement among raft nodes before linearized reading' (duration: 262.069099ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:03:53.708292Z","caller":"traceutil/trace.go:171","msg":"trace[1127167184] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:592; }","duration":"305.058652ms","start":"2026-05-13T22:03:53.403202Z","end":"2026-05-13T22:03:53.708261Z","steps":["trace[1127167184] 'agreement among raft nodes before linearized reading' (duration: 296.726864ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:03:53.708308Z","caller":"traceutil/trace.go:171","msg":"trace[1436737577] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:592; }","duration":"734.948358ms","start":"2026-05-13T22:03:52.973347Z","end":"2026-05-13T22:03:53.708295Z","steps":["trace[1436737577] 'agreement among raft nodes before linearized reading' (duration: 726.406824ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:53.708328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:53.099976Z","time spent":"608.307187ms","remote":"127.0.0.1:40116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"info","ts":"2026-05-13T22:03:53.708379Z","caller":"traceutil/trace.go:171","msg":"trace[2071820470] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:592; }","duration":"571.335245ms","start":"2026-05-13T22:03:53.137034Z","end":"2026-05-13T22:03:53.708369Z","steps":["trace[2071820470] 'agreement among raft nodes before linearized reading' (duration: 562.873324ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:03:53.708384Z","caller":"traceutil/trace.go:171","msg":"trace[624485022] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:592; }","duration":"608.311268ms","start":"2026-05-13T22:03:53.100063Z","end":"2026-05-13T22:03:53.708374Z","steps":["trace[624485022] 'agreement among raft nodes before linearized reading' (duration: 599.841716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:53.708412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:53.137022Z","time spent":"571.379176ms","remote":"127.0.0.1:40132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 "} {"level":"info","ts":"2026-05-13T22:03:53.708447Z","caller":"traceutil/trace.go:171","msg":"trace[996316914] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:592; }","duration":"305.453151ms","start":"2026-05-13T22:03:53.402986Z","end":"2026-05-13T22:03:53.708439Z","steps":["trace[996316914] 'agreement among raft nodes before linearized reading' (duration: 296.966509ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:03:53.708467Z","caller":"traceutil/trace.go:171","msg":"trace[75295996] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:592; }","duration":"287.973345ms","start":"2026-05-13T22:03:53.42048Z","end":"2026-05-13T22:03:53.708453Z","steps":["trace[75295996] 'agreement among raft nodes before linearized reading' (duration: 279.403792ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:03:53.708565Z","caller":"traceutil/trace.go:171","msg":"trace[693697859] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:592; }","duration":"288.052617ms","start":"2026-05-13T22:03:53.420499Z","end":"2026-05-13T22:03:53.708552Z","steps":["trace[693697859] 'agreement among raft nodes before linearized reading' (duration: 279.36539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:53.708418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:53.100038Z","time spent":"608.368538ms","remote":"127.0.0.1:40116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-13T22:03:53.708611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:53.402969Z","time spent":"305.508053ms","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"info","ts":"2026-05-13T22:03:53.708212Z","caller":"traceutil/trace.go:171","msg":"trace[277498169] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:592; }","duration":"607.083257ms","start":"2026-05-13T22:03:53.101042Z","end":"2026-05-13T22:03:53.708125Z","steps":["trace[277498169] 'agreement among raft nodes before linearized reading' (duration: 598.817871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:53.70835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:52.973326Z","time spent":"735.00944ms","remote":"127.0.0.1:40104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 "} {"level":"warn","ts":"2026-05-13T22:03:53.708682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:53.101034Z","time spent":"607.62249ms","remote":"127.0.0.1:40116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-13T22:03:53.708349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:53.403186Z","time spent":"305.136423ms","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-05-13T22:03:53.70862Z","caller":"traceutil/trace.go:171","msg":"trace[2051903595] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:592; }","duration":"270.891059ms","start":"2026-05-13T22:03:53.437718Z","end":"2026-05-13T22:03:53.708609Z","steps":["trace[2051903595] 'agreement among raft nodes before linearized reading' (duration: 262.182462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:54.634938Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880869378639849481,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-13T22:03:55.13572Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880869378639849481,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-13T22:03:55.353049Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.222091372s","expected-duration":"1s"} {"level":"info","ts":"2026-05-13T22:03:55.354305Z","caller":"traceutil/trace.go:171","msg":"trace[1516952914] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"1.220263725s","start":"2026-05-13T22:03:54.13402Z","end":"2026-05-13T22:03:55.354283Z","steps":["trace[1516952914] 'read index received' (duration: 1.22004572s)","trace[1516952914] 'applied index is now lower than readState.Index' (duration: 217.105µs)"],"step_count":2} {"level":"info","ts":"2026-05-13T22:03:55.354398Z","caller":"traceutil/trace.go:171","msg":"trace[1400800710] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"1.223510057s","start":"2026-05-13T22:03:54.130833Z","end":"2026-05-13T22:03:55.354343Z","steps":["trace[1400800710] 'process raft request' (duration: 1.223287291s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:55.354506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.219777194s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-13T22:03:55.354539Z","caller":"traceutil/trace.go:171","msg":"trace[224180239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:593; }","duration":"1.219840475s","start":"2026-05-13T22:03:54.134689Z","end":"2026-05-13T22:03:55.354529Z","steps":["trace[224180239] 'agreement among raft nodes before linearized reading' (duration: 1.219684491s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:55.354554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:54.130807Z","time spent":"1.22366119s","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T22:03:55.354572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:54.134684Z","time spent":"1.219880646s","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-13T22:03:55.354624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.220579193s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-13T22:03:55.354703Z","caller":"traceutil/trace.go:171","msg":"trace[237676671] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:593; }","duration":"1.220668735s","start":"2026-05-13T22:03:54.134017Z","end":"2026-05-13T22:03:55.354686Z","steps":["trace[237676671] 'agreement among raft nodes before linearized reading' (duration: 1.220354237s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:55.354707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.188409361s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-13T22:03:55.354744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:54.133988Z","time spent":"1.220747007s","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-13T22:03:55.35474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.887164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-13T22:03:55.354756Z","caller":"traceutil/trace.go:171","msg":"trace[1799515903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"1.188501023s","start":"2026-05-13T22:03:54.166243Z","end":"2026-05-13T22:03:55.354744Z","steps":["trace[1799515903] 'agreement among raft nodes before linearized reading' (duration: 1.188417871s)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:03:55.354784Z","caller":"traceutil/trace.go:171","msg":"trace[305290512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:593; }","duration":"659.991106ms","start":"2026-05-13T22:03:54.694781Z","end":"2026-05-13T22:03:55.354772Z","steps":["trace[305290512] 'agreement among raft nodes before linearized reading' (duration: 659.858113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:55.354794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:54.166223Z","time spent":"1.188564575s","remote":"127.0.0.1:52244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-13T22:03:55.35482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:54.694761Z","time spent":"660.048408ms","remote":"127.0.0.1:52556","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-05-13T22:03:55.86398Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880869378639849485,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-13T22:03:56.36466Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880869378639849485,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-13T22:03:56.397184Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.037408095s","expected-duration":"1s"} {"level":"info","ts":"2026-05-13T22:03:56.397624Z","caller":"traceutil/trace.go:171","msg":"trace[391993175] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"1.037908007s","start":"2026-05-13T22:03:55.359692Z","end":"2026-05-13T22:03:56.3976Z","steps":["trace[391993175] 'process raft request' (duration: 1.037750814s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:56.397824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:55.359675Z","time spent":"1.03802071s","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T22:03:56.399204Z","caller":"traceutil/trace.go:171","msg":"trace[2013558088] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:615; }","duration":"1.036284856s","start":"2026-05-13T22:03:55.362895Z","end":"2026-05-13T22:03:56.39918Z","steps":["trace[2013558088] 'read index received' (duration: 1.034661266s)","trace[2013558088] 'applied index is now lower than readState.Index' (duration: 1.62272ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T22:03:56.399486Z","caller":"traceutil/trace.go:171","msg":"trace[248253740] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"1.039710641s","start":"2026-05-13T22:03:55.359757Z","end":"2026-05-13T22:03:56.399468Z","steps":["trace[248253740] 'process raft request' (duration: 1.039225179s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:56.399516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.036603784s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:03:56.399553Z","caller":"traceutil/trace.go:171","msg":"trace[830303883] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"1.036672546s","start":"2026-05-13T22:03:55.36287Z","end":"2026-05-13T22:03:56.399542Z","steps":["trace[830303883] 'agreement among raft nodes before linearized reading' (duration: 1.036598194s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:56.399589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:55.362857Z","time spent":"1.036725437s","remote":"127.0.0.1:52244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-13T22:03:56.399599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:55.359728Z","time spent":"1.039807134s","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T22:03:56.399594Z","caller":"traceutil/trace.go:171","msg":"trace[1430411108] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"1.036660726s","start":"2026-05-13T22:03:55.362898Z","end":"2026-05-13T22:03:56.399559Z","steps":["trace[1430411108] 'process raft request' (duration: 1.036214435s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:56.399707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.644435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.229\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-13T22:03:56.399755Z","caller":"traceutil/trace.go:171","msg":"trace[672018286] range","detail":"{range_begin:/registry/masterleases/199.204.45.229; range_end:; response_count:1; response_revision:597; }","duration":"408.694987ms","start":"2026-05-13T22:03:55.991049Z","end":"2026-05-13T22:03:56.399744Z","steps":["trace[672018286] 'agreement among raft nodes before linearized reading' (duration: 408.45964ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:56.399762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:55.362883Z","time spent":"1.036793029s","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T22:03:56.399792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:55.990971Z","time spent":"408.81467ms","remote":"127.0.0.1:52302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.229\" "} {"level":"warn","ts":"2026-05-13T22:03:58.460653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.352157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-13T22:03:58.46077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.788645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:03:58.460845Z","caller":"traceutil/trace.go:171","msg":"trace[2073629772] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"292.937768ms","start":"2026-05-13T22:03:58.167891Z","end":"2026-05-13T22:03:58.460829Z","steps":["trace[2073629772] 'range keys from in-memory index tree' (duration: 292.699372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:58.46084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.197825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:03:58.460928Z","caller":"traceutil/trace.go:171","msg":"trace[1111158669] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:627; }","duration":"216.356048ms","start":"2026-05-13T22:03:58.244553Z","end":"2026-05-13T22:03:58.460909Z","steps":["trace[1111158669] 'range keys from in-memory index tree' (duration: 216.069181ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:03:58.460781Z","caller":"traceutil/trace.go:171","msg":"trace[1778079663] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:627; }","duration":"416.480951ms","start":"2026-05-13T22:03:58.044238Z","end":"2026-05-13T22:03:58.460719Z","steps":["trace[1778079663] 'range keys from in-memory index tree' (duration: 416.223105ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:03:58.46099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:03:58.04422Z","time spent":"416.756088ms","remote":"127.0.0.1:52308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"info","ts":"2026-05-13T22:04:01.197078Z","caller":"traceutil/trace.go:171","msg":"trace[659660051] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"123.591593ms","start":"2026-05-13T22:04:01.073452Z","end":"2026-05-13T22:04:01.197044Z","steps":["trace[659660051] 'process raft request' (duration: 123.407519ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:04:08.933235Z","caller":"traceutil/trace.go:171","msg":"trace[1982671988] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"414.588694ms","start":"2026-05-13T22:04:08.518622Z","end":"2026-05-13T22:04:08.93321Z","steps":["trace[1982671988] 'process raft request' (duration: 414.44936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:04:08.933527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:04:08.518602Z","time spent":"414.833051ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T22:04:08.935559Z","caller":"traceutil/trace.go:171","msg":"trace[1626671531] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"414.228384ms","start":"2026-05-13T22:04:08.521306Z","end":"2026-05-13T22:04:08.935534Z","steps":["trace[1626671531] 'process raft request' (duration: 414.012669ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:04:08.93565Z","caller":"traceutil/trace.go:171","msg":"trace[551067271] linearizableReadLoop","detail":"{readStateIndex:756; appliedIndex:754; }","duration":"410.306037ms","start":"2026-05-13T22:04:08.525326Z","end":"2026-05-13T22:04:08.935632Z","steps":["trace[551067271] 'read index received' (duration: 407.732982ms)","trace[551067271] 'applied index is now lower than readState.Index' (duration: 2.571435ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T22:04:08.935716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:04:08.521267Z","time spent":"414.371749ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T22:04:08.93587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.506062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-13T22:04:08.935963Z","caller":"traceutil/trace.go:171","msg":"trace[245127615] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:731; }","duration":"410.633346ms","start":"2026-05-13T22:04:08.5253Z","end":"2026-05-13T22:04:08.935934Z","steps":["trace[245127615] 'agreement among raft nodes before linearized reading' (duration: 410.397209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:04:08.936033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:04:08.525289Z","time spent":"410.730198ms","remote":"127.0.0.1:52556","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-05-13T22:04:08.936039Z","caller":"traceutil/trace.go:171","msg":"trace[1764979332] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"189.314444ms","start":"2026-05-13T22:04:08.746678Z","end":"2026-05-13T22:04:08.935993Z","steps":["trace[1764979332] 'process raft request' (duration: 188.80931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:04:11.96032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.309367ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-13T22:04:11.960427Z","caller":"traceutil/trace.go:171","msg":"trace[926688084] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"526.002964ms","start":"2026-05-13T22:04:11.434403Z","end":"2026-05-13T22:04:11.960406Z","steps":["trace[926688084] 'process raft request' (duration: 14.544424ms)","trace[926688084] 'compare' (duration: 511.192464ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T22:04:11.960527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:04:11.434373Z","time spent":"526.115367ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T22:04:13.227232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.193493668s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-05-13T22:04:13.240729Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.206942634s","expected-duration":"1s"} {"level":"info","ts":"2026-05-13T22:04:13.241022Z","caller":"traceutil/trace.go:171","msg":"trace[1953873020] linearizableReadLoop","detail":"{readStateIndex:775; appliedIndex:773; }","duration":"1.310521488s","start":"2026-05-13T22:04:11.930485Z","end":"2026-05-13T22:04:13.241006Z","steps":["trace[1953873020] 'read index received' (duration: 103.256776ms)","trace[1953873020] 'applied index is now lower than readState.Index' (duration: 1.207264122s)"],"step_count":2} {"level":"warn","ts":"2026-05-13T22:04:13.241147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.310644701s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-13T22:04:13.241127Z","caller":"traceutil/trace.go:171","msg":"trace[229497361] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"1.316804935s","start":"2026-05-13T22:04:11.924264Z","end":"2026-05-13T22:04:13.241069Z","steps":["trace[229497361] 'process raft request' (duration: 1.316633301s)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:04:13.241183Z","caller":"traceutil/trace.go:171","msg":"trace[731964859] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:748; }","duration":"1.310711993s","start":"2026-05-13T22:04:11.930452Z","end":"2026-05-13T22:04:13.241164Z","steps":["trace[731964859] 'agreement among raft nodes before linearized reading' (duration: 1.31061146s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:04:13.241161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.358449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-13T22:04:13.241205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:04:11.930434Z","time spent":"1.310765695s","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-13T22:04:13.241235Z","caller":"traceutil/trace.go:171","msg":"trace[1671170385] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:748; }","duration":"594.469582ms","start":"2026-05-13T22:04:12.646744Z","end":"2026-05-13T22:04:13.241213Z","steps":["trace[1671170385] 'agreement among raft nodes before linearized reading' (duration: 594.357069ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:04:13.241308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:04:12.646725Z","time spent":"594.572015ms","remote":"127.0.0.1:52244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-13T22:04:13.241318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:04:11.92424Z","time spent":"1.316951328s","remote":"127.0.0.1:52522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T22:04:13.24135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.130534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-13T22:04:13.241415Z","caller":"traceutil/trace.go:171","msg":"trace[1310488462] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:748; }","duration":"289.221296ms","start":"2026-05-13T22:04:12.952177Z","end":"2026-05-13T22:04:13.241398Z","steps":["trace[1310488462] 'agreement among raft nodes before linearized reading' (duration: 288.982861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:04:13.241448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.479412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-13T22:04:13.241493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.40671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-13T22:04:13.241514Z","caller":"traceutil/trace.go:171","msg":"trace[1082634394] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:748; }","duration":"295.461631ms","start":"2026-05-13T22:04:12.946047Z","end":"2026-05-13T22:04:13.241509Z","steps":["trace[1082634394] 'agreement among raft nodes before linearized reading' (duration: 295.4123ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:04:13.241531Z","caller":"traceutil/trace.go:171","msg":"trace[185156421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:748; }","duration":"293.591374ms","start":"2026-05-13T22:04:12.947917Z","end":"2026-05-13T22:04:13.241508Z","steps":["trace[185156421] 'agreement among raft nodes before linearized reading' (duration: 293.382869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:04:13.241784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.140916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-fth5h\" ","response":"range_response_count:1 size:5050"} {"level":"info","ts":"2026-05-13T22:04:13.241829Z","caller":"traceutil/trace.go:171","msg":"trace[1279404797] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-fth5h; range_end:; response_count:1; response_revision:748; }","duration":"271.204227ms","start":"2026-05-13T22:04:12.970612Z","end":"2026-05-13T22:04:13.241817Z","steps":["trace[1279404797] 'agreement among raft nodes before linearized reading' (duration: 271.055773ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:04:48.397811Z","caller":"traceutil/trace.go:171","msg":"trace[574388151] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1009; }","duration":"248.573588ms","start":"2026-05-13T22:04:48.149214Z","end":"2026-05-13T22:04:48.397787Z","steps":["trace[574388151] 'read index received' (duration: 248.405324ms)","trace[574388151] 'applied index is now lower than readState.Index' (duration: 167.564µs)"],"step_count":2} {"level":"warn","ts":"2026-05-13T22:04:48.397967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.795122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-13T22:04:48.398053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.815705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-13T22:04:48.398198Z","caller":"traceutil/trace.go:171","msg":"trace[899861207] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"272.53299ms","start":"2026-05-13T22:04:48.125285Z","end":"2026-05-13T22:04:48.397818Z","steps":["trace[899861207] 'process raft request' (duration: 272.366826ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:04:48.398279Z","caller":"traceutil/trace.go:171","msg":"trace[537338010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:974; }","duration":"230.858463ms","start":"2026-05-13T22:04:48.167129Z","end":"2026-05-13T22:04:48.397988Z","steps":["trace[537338010] 'agreement among raft nodes before linearized reading' (duration: 230.792381ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:04:48.398364Z","caller":"traceutil/trace.go:171","msg":"trace[2031417636] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:974; }","duration":"248.949597ms","start":"2026-05-13T22:04:48.14918Z","end":"2026-05-13T22:04:48.39813Z","steps":["trace[2031417636] 'agreement among raft nodes before linearized reading' (duration: 248.66165ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:04:48.98474Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880869378639850272,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-13T22:04:49.418367Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.015282039s","expected-duration":"1s"} {"level":"info","ts":"2026-05-13T22:04:49.418677Z","caller":"traceutil/trace.go:171","msg":"trace[1008663790] linearizableReadLoop","detail":"{readStateIndex:1011; appliedIndex:1010; }","duration":"934.388891ms","start":"2026-05-13T22:04:48.484268Z","end":"2026-05-13T22:04:49.418656Z","steps":["trace[1008663790] 'read index received' (duration: 934.212625ms)","trace[1008663790] 'applied index is now lower than readState.Index' (duration: 175.616µs)"],"step_count":2} {"level":"warn","ts":"2026-05-13T22:04:49.418765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.475533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-13T22:04:49.418799Z","caller":"traceutil/trace.go:171","msg":"trace[293869818] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:975; }","duration":"934.554564ms","start":"2026-05-13T22:04:48.484236Z","end":"2026-05-13T22:04:49.41879Z","steps":["trace[293869818] 'agreement among raft nodes before linearized reading' (duration: 934.479752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:04:49.418832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:04:48.484221Z","time spent":"934.602846ms","remote":"127.0.0.1:52466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":29,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-05-13T22:04:49.418872Z","caller":"traceutil/trace.go:171","msg":"trace[350227394] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"1.015889346s","start":"2026-05-13T22:04:48.402968Z","end":"2026-05-13T22:04:49.418858Z","steps":["trace[350227394] 'process raft request' (duration: 1.015563816s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:04:49.418965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:04:48.402941Z","time spent":"1.015977857s","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T22:04:50.135157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.365187ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-13T22:04:50.135272Z","caller":"traceutil/trace.go:171","msg":"trace[452671534] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1011; }","duration":"685.39512ms","start":"2026-05-13T22:04:49.449865Z","end":"2026-05-13T22:04:50.13526Z","steps":["trace[452671534] 'read index received' (duration: 137.680775ms)","trace[452671534] 'applied index is now lower than readState.Index' (duration: 547.713555ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T22:04:50.135416Z","caller":"traceutil/trace.go:171","msg":"trace[1446391971] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"694.965471ms","start":"2026-05-13T22:04:49.440442Z","end":"2026-05-13T22:04:50.135407Z","steps":["trace[1446391971] 'process raft request' (duration: 147.281256ms)","trace[1446391971] 'compare' (duration: 547.1003ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T22:04:50.135455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:04:49.440428Z","time spent":"695.006742ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T22:04:50.135624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.7527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-13T22:04:50.13569Z","caller":"traceutil/trace.go:171","msg":"trace[526703082] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:977; }","duration":"685.821711ms","start":"2026-05-13T22:04:49.44985Z","end":"2026-05-13T22:04:50.135672Z","steps":["trace[526703082] 'agreement among raft nodes before linearized reading' (duration: 685.640717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:04:50.135736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:04:49.449843Z","time spent":"685.881903ms","remote":"127.0.0.1:52556","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-05-13T22:04:50.135718Z","caller":"traceutil/trace.go:171","msg":"trace[640446258] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"695.244458ms","start":"2026-05-13T22:04:49.44044Z","end":"2026-05-13T22:04:50.135685Z","steps":["trace[640446258] 'process raft request' (duration: 694.782036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:04:50.135872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:04:49.440424Z","time spent":"695.366541ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T22:04:50.137801Z","caller":"traceutil/trace.go:171","msg":"trace[1374064281] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"410.880371ms","start":"2026-05-13T22:04:49.726906Z","end":"2026-05-13T22:04:50.137786Z","steps":["trace[1374064281] 'process raft request' (duration: 410.741037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:04:50.137879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:04:49.726875Z","time spent":"410.968803ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T22:04:50.137879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.806575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-13T22:04:50.137935Z","caller":"traceutil/trace.go:171","msg":"trace[1112974817] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:978; }","duration":"678.892977ms","start":"2026-05-13T22:04:49.459026Z","end":"2026-05-13T22:04:50.137919Z","steps":["trace[1112974817] 'agreement among raft nodes before linearized reading' (duration: 678.732393ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:04:50.137973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:04:49.459015Z","time spent":"678.947719ms","remote":"127.0.0.1:52556","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-05-13T22:05:12.751579Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880869378639850474,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-13T22:05:12.821581Z","caller":"traceutil/trace.go:171","msg":"trace[1084997356] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"575.429922ms","start":"2026-05-13T22:05:12.246124Z","end":"2026-05-13T22:05:12.821554Z","steps":["trace[1084997356] 'process raft request' (duration: 575.286088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:12.821733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:12.246104Z","time spent":"575.554085ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T22:05:13.315162Z","caller":"traceutil/trace.go:171","msg":"trace[584840371] linearizableReadLoop","detail":"{readStateIndex:1092; appliedIndex:1089; }","duration":"1.064174347s","start":"2026-05-13T22:05:12.250964Z","end":"2026-05-13T22:05:13.315138Z","steps":["trace[584840371] 'read index received' (duration: 570.480717ms)","trace[584840371] 'applied index is now lower than readState.Index' (duration: 493.69276ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T22:05:13.31523Z","caller":"traceutil/trace.go:171","msg":"trace[76998057] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"1.067732756s","start":"2026-05-13T22:05:12.247464Z","end":"2026-05-13T22:05:13.315197Z","steps":["trace[76998057] 'process raft request' (duration: 1.06748486s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:13.315392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.195285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-13T22:05:13.315419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:12.247447Z","time spent":"1.067852529s","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T22:05:13.315477Z","caller":"traceutil/trace.go:171","msg":"trace[185951639] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1051; }","duration":"147.38977ms","start":"2026-05-13T22:05:13.168069Z","end":"2026-05-13T22:05:13.315459Z","steps":["trace[185951639] 'agreement among raft nodes before linearized reading' (duration: 147.226656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:13.315471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064483834s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-13T22:05:13.315485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064266479s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-13T22:05:13.315562Z","caller":"traceutil/trace.go:171","msg":"trace[1929176489] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1051; }","duration":"1.064584977s","start":"2026-05-13T22:05:12.250941Z","end":"2026-05-13T22:05:13.315526Z","steps":["trace[1929176489] 'agreement among raft nodes before linearized reading' (duration: 1.064319501s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:13.315612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:12.250932Z","time spent":"1.064670369s","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-13T22:05:13.315623Z","caller":"traceutil/trace.go:171","msg":"trace[1284514988] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1051; }","duration":"1.064418193s","start":"2026-05-13T22:05:12.251133Z","end":"2026-05-13T22:05:13.315551Z","steps":["trace[1284514988] 'agreement among raft nodes before linearized reading' (duration: 1.064142586s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:13.315587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.909216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-13T22:05:13.315779Z","caller":"traceutil/trace.go:171","msg":"trace[266194555] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1051; }","duration":"914.247074ms","start":"2026-05-13T22:05:12.401504Z","end":"2026-05-13T22:05:13.315751Z","steps":["trace[266194555] 'agreement among raft nodes before linearized reading' (duration: 913.832624ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:13.3159Z","caller":"traceutil/trace.go:171","msg":"trace[1404502233] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"775.516043ms","start":"2026-05-13T22:05:12.539703Z","end":"2026-05-13T22:05:13.315219Z","steps":["trace[1404502233] 'process raft request' (duration: 775.39515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:13.315903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:12.401473Z","time spent":"914.388438ms","remote":"127.0.0.1:52714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-05-13T22:05:13.315695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:12.251127Z","time spent":"1.064529825s","remote":"127.0.0.1:52556","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-05-13T22:05:13.316123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:12.539679Z","time spent":"776.298512ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T22:05:15.649431Z","caller":"traceutil/trace.go:171","msg":"trace[2136276677] linearizableReadLoop","detail":"{readStateIndex:1096; appliedIndex:1095; }","duration":"482.55804ms","start":"2026-05-13T22:05:15.16685Z","end":"2026-05-13T22:05:15.649408Z","steps":["trace[2136276677] 'read index received' (duration: 482.413777ms)","trace[2136276677] 'applied index is now lower than readState.Index' (duration: 143.373µs)"],"step_count":2} {"level":"info","ts":"2026-05-13T22:05:15.649517Z","caller":"traceutil/trace.go:171","msg":"trace[150380057] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"819.720532ms","start":"2026-05-13T22:05:14.829757Z","end":"2026-05-13T22:05:15.649477Z","steps":["trace[150380057] 'process raft request' (duration: 819.509207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:15.649561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.703943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:05:15.649601Z","caller":"traceutil/trace.go:171","msg":"trace[213659802] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1055; }","duration":"482.755935ms","start":"2026-05-13T22:05:15.166834Z","end":"2026-05-13T22:05:15.64959Z","steps":["trace[213659802] 'agreement among raft nodes before linearized reading' (duration: 482.684033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:15.649637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:15.166744Z","time spent":"482.883188ms","remote":"127.0.0.1:52244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-13T22:05:15.649702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:14.82974Z","time spent":"819.867935ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T22:05:15.961023Z","caller":"traceutil/trace.go:171","msg":"trace[314827043] linearizableReadLoop","detail":"{readStateIndex:1097; appliedIndex:1096; }","duration":"311.501148ms","start":"2026-05-13T22:05:15.649498Z","end":"2026-05-13T22:05:15.960999Z","steps":["trace[314827043] 'read index received' (duration: 311.342204ms)","trace[314827043] 'applied index is now lower than readState.Index' (duration: 157.754µs)"],"step_count":2} {"level":"warn","ts":"2026-05-13T22:05:15.961221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.060283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-13T22:05:15.961278Z","caller":"traceutil/trace.go:171","msg":"trace[500542122] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1056; }","duration":"583.150024ms","start":"2026-05-13T22:05:15.378114Z","end":"2026-05-13T22:05:15.961264Z","steps":["trace[500542122] 'agreement among raft nodes before linearized reading' (duration: 582.922529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:15.961302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.559904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-13T22:05:15.961315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:15.378101Z","time spent":"583.206216ms","remote":"127.0.0.1:52556","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":"info","ts":"2026-05-13T22:05:15.961345Z","caller":"traceutil/trace.go:171","msg":"trace[694959739] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1056; }","duration":"310.622236ms","start":"2026-05-13T22:05:15.650706Z","end":"2026-05-13T22:05:15.961328Z","steps":["trace[694959739] 'agreement among raft nodes before linearized reading' (duration: 310.565935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:15.96138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:15.650693Z","time spent":"310.679637ms","remote":"127.0.0.1:52246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-13T22:05:15.961354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.146503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-13T22:05:15.961424Z","caller":"traceutil/trace.go:171","msg":"trace[713671808] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"637.210461ms","start":"2026-05-13T22:05:15.324203Z","end":"2026-05-13T22:05:15.961413Z","steps":["trace[713671808] 'process raft request' (duration: 636.660327ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:15.961432Z","caller":"traceutil/trace.go:171","msg":"trace[856046721] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1056; }","duration":"665.284416ms","start":"2026-05-13T22:05:15.296127Z","end":"2026-05-13T22:05:15.961412Z","steps":["trace[856046721] 'agreement among raft nodes before linearized reading' (duration: 665.107692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:15.96148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:15.296103Z","time spent":"665.367207ms","remote":"127.0.0.1:52764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":29,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-05-13T22:05:15.961499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:15.324181Z","time spent":"637.264343ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T22:05:15.961724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.673328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-13T22:05:15.961752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.149476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-13T22:05:15.961759Z","caller":"traceutil/trace.go:171","msg":"trace[2134483528] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1056; }","duration":"367.743549ms","start":"2026-05-13T22:05:15.594002Z","end":"2026-05-13T22:05:15.961746Z","steps":["trace[2134483528] 'agreement among raft nodes before linearized reading' (duration: 367.653896ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:15.961786Z","caller":"traceutil/trace.go:171","msg":"trace[1112726939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1056; }","duration":"642.216828ms","start":"2026-05-13T22:05:15.319559Z","end":"2026-05-13T22:05:15.961776Z","steps":["trace[1112726939] 'agreement among raft nodes before linearized reading' (duration: 642.116155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:15.961788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:15.593982Z","time spent":"367.799301ms","remote":"127.0.0.1:52556","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-05-13T22:05:15.961221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.188554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-13T22:05:15.961812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:15.319541Z","time spent":"642.26505ms","remote":"127.0.0.1:52556","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":"info","ts":"2026-05-13T22:05:15.961847Z","caller":"traceutil/trace.go:171","msg":"trace[131566869] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1056; }","duration":"610.861291ms","start":"2026-05-13T22:05:15.350974Z","end":"2026-05-13T22:05:15.961835Z","steps":["trace[131566869] 'agreement among raft nodes before linearized reading' (duration: 610.141693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:15.961886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:15.350959Z","time spent":"610.916042ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-13T22:05:16.131814Z","caller":"traceutil/trace.go:171","msg":"trace[800209484] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"164.18099ms","start":"2026-05-13T22:05:15.967608Z","end":"2026-05-13T22:05:16.131789Z","steps":["trace[800209484] 'process raft request' (duration: 164.079128ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:16.131852Z","caller":"traceutil/trace.go:171","msg":"trace[2015968846] linearizableReadLoop","detail":"{readStateIndex:1101; appliedIndex:1097; }","duration":"137.575052ms","start":"2026-05-13T22:05:15.994254Z","end":"2026-05-13T22:05:16.131829Z","steps":["trace[2015968846] 'read index received' (duration: 28.028383ms)","trace[2015968846] 'applied index is now lower than readState.Index' (duration: 109.545379ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T22:05:16.131845Z","caller":"traceutil/trace.go:171","msg":"trace[1160431223] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"164.218751ms","start":"2026-05-13T22:05:15.967592Z","end":"2026-05-13T22:05:16.131811Z","steps":["trace[1160431223] 'process raft request' (duration: 82.523441ms)","trace[1160431223] 'compare' (duration: 81.433603ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T22:05:16.132065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.807058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.229\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-13T22:05:16.132092Z","caller":"traceutil/trace.go:171","msg":"trace[783576011] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"164.19594ms","start":"2026-05-13T22:05:15.96771Z","end":"2026-05-13T22:05:16.131906Z","steps":["trace[783576011] 'process raft request' (duration: 164.040816ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:16.132119Z","caller":"traceutil/trace.go:171","msg":"trace[1659561839] range","detail":"{range_begin:/registry/masterleases/199.204.45.229; range_end:; response_count:1; response_revision:1060; }","duration":"137.912881ms","start":"2026-05-13T22:05:15.994196Z","end":"2026-05-13T22:05:16.132109Z","steps":["trace[1659561839] 'agreement among raft nodes before linearized reading' (duration: 137.715076ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:17.936867Z","caller":"traceutil/trace.go:171","msg":"trace[454022460] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1103; }","duration":"223.592482ms","start":"2026-05-13T22:05:17.713256Z","end":"2026-05-13T22:05:17.936849Z","steps":["trace[454022460] 'read index received' (duration: 223.447468ms)","trace[454022460] 'applied index is now lower than readState.Index' (duration: 144.184µs)"],"step_count":2} {"level":"warn","ts":"2026-05-13T22:05:17.936988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.703644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-13T22:05:17.937013Z","caller":"traceutil/trace.go:171","msg":"trace[1890039376] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1062; }","duration":"223.786557ms","start":"2026-05-13T22:05:17.713221Z","end":"2026-05-13T22:05:17.937007Z","steps":["trace[1890039376] 'agreement among raft nodes before linearized reading' (duration: 223.709575ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:17.937051Z","caller":"traceutil/trace.go:171","msg":"trace[1546433300] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"279.583147ms","start":"2026-05-13T22:05:17.657331Z","end":"2026-05-13T22:05:17.936914Z","steps":["trace[1546433300] 'process raft request' (duration: 279.398913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:18.119328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.17147ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-13T22:05:18.11967Z","caller":"traceutil/trace.go:171","msg":"trace[2139879958] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"151.780419ms","start":"2026-05-13T22:05:17.967834Z","end":"2026-05-13T22:05:18.119615Z","steps":["trace[2139879958] 'process raft request' (duration: 44.222609ms)","trace[2139879958] 'compare' (duration: 107.050437ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T22:05:18.572165Z","caller":"traceutil/trace.go:171","msg":"trace[785022978] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"287.900615ms","start":"2026-05-13T22:05:18.284242Z","end":"2026-05-13T22:05:18.572142Z","steps":["trace[785022978] 'process raft request' (duration: 287.859664ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:18.572259Z","caller":"traceutil/trace.go:171","msg":"trace[1612556182] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"448.182018ms","start":"2026-05-13T22:05:18.124028Z","end":"2026-05-13T22:05:18.57221Z","steps":["trace[1612556182] 'process raft request' (duration: 365.825872ms)","trace[1612556182] 'compare' (duration: 82.029468ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T22:05:18.572428Z","caller":"traceutil/trace.go:171","msg":"trace[1869282410] linearizableReadLoop","detail":"{readStateIndex:1106; appliedIndex:1105; }","duration":"436.424922ms","start":"2026-05-13T22:05:18.13599Z","end":"2026-05-13T22:05:18.572414Z","steps":["trace[1869282410] 'read index received' (duration: 353.885631ms)","trace[1869282410] 'applied index is now lower than readState.Index' (duration: 82.537881ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T22:05:18.572674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.665519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-13T22:05:18.572722Z","caller":"traceutil/trace.go:171","msg":"trace[772639456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1065; }","duration":"436.749021ms","start":"2026-05-13T22:05:18.135963Z","end":"2026-05-13T22:05:18.572712Z","steps":["trace[772639456] 'agreement among raft nodes before linearized reading' (duration: 436.574655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:18.572748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:18.13595Z","time spent":"436.791141ms","remote":"127.0.0.1:52556","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-05-13T22:05:18.572767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.893383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-13T22:05:18.572787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.753931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-13T22:05:18.572828Z","caller":"traceutil/trace.go:171","msg":"trace[1533065240] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1065; }","duration":"406.951214ms","start":"2026-05-13T22:05:18.16586Z","end":"2026-05-13T22:05:18.572812Z","steps":["trace[1533065240] 'agreement among raft nodes before linearized reading' (duration: 406.855642ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:18.57284Z","caller":"traceutil/trace.go:171","msg":"trace[101511893] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1065; }","duration":"436.852274ms","start":"2026-05-13T22:05:18.135975Z","end":"2026-05-13T22:05:18.572827Z","steps":["trace[101511893] 'agreement among raft nodes before linearized reading' (duration: 436.6928ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:18.572872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:18.135953Z","time spent":"436.912984ms","remote":"127.0.0.1:52556","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-05-13T22:05:18.572867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:18.165819Z","time spent":"407.039786ms","remote":"127.0.0.1:52244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-13T22:05:18.572862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.078049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-13T22:05:18.572899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.557514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-13T22:05:18.572977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.939445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-13T22:05:18.572968Z","caller":"traceutil/trace.go:171","msg":"trace[319449605] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1065; }","duration":"363.644616ms","start":"2026-05-13T22:05:18.209295Z","end":"2026-05-13T22:05:18.57294Z","steps":["trace[319449605] 'agreement among raft nodes before linearized reading' (duration: 363.532524ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:18.572993Z","caller":"traceutil/trace.go:171","msg":"trace[26793544] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1065; }","duration":"436.956005ms","start":"2026-05-13T22:05:18.136032Z","end":"2026-05-13T22:05:18.572988Z","steps":["trace[26793544] 'agreement among raft nodes before linearized reading' (duration: 436.908014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:18.573008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:18.135974Z","time spent":"437.030458ms","remote":"127.0.0.1:52556","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-05-13T22:05:18.573007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:18.209281Z","time spent":"363.716418ms","remote":"127.0.0.1:52572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-05-13T22:05:18.573048Z","caller":"traceutil/trace.go:171","msg":"trace[1700760014] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1065; }","duration":"376.221453ms","start":"2026-05-13T22:05:18.196699Z","end":"2026-05-13T22:05:18.572921Z","steps":["trace[1700760014] 'agreement among raft nodes before linearized reading' (duration: 375.990477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:18.573128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:18.196678Z","time spent":"376.422407ms","remote":"127.0.0.1:52308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-05-13T22:05:18.574148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:18.124007Z","time spent":"450.065385ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T22:05:18.729018Z","caller":"traceutil/trace.go:171","msg":"trace[288119710] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"151.540273ms","start":"2026-05-13T22:05:18.577462Z","end":"2026-05-13T22:05:18.729003Z","steps":["trace[288119710] 'process raft request' (duration: 151.514233ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:18.729209Z","caller":"traceutil/trace.go:171","msg":"trace[173111432] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"151.718577ms","start":"2026-05-13T22:05:18.577445Z","end":"2026-05-13T22:05:18.729164Z","steps":["trace[173111432] 'process raft request' (duration: 151.42219ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:30.293152Z","caller":"traceutil/trace.go:171","msg":"trace[1128516459] linearizableReadLoop","detail":"{readStateIndex:1199; appliedIndex:1198; }","duration":"164.17664ms","start":"2026-05-13T22:05:30.12896Z","end":"2026-05-13T22:05:30.293137Z","steps":["trace[1128516459] 'read index received' (duration: 164.057397ms)","trace[1128516459] 'applied index is now lower than readState.Index' (duration: 118.543µs)"],"step_count":2} {"level":"info","ts":"2026-05-13T22:05:30.293205Z","caller":"traceutil/trace.go:171","msg":"trace[1595666330] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"306.642896ms","start":"2026-05-13T22:05:29.986533Z","end":"2026-05-13T22:05:30.293176Z","steps":["trace[1595666330] 'process raft request' (duration: 306.499392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:30.293337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:29.986522Z","time spent":"306.743098ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T22:05:30.293232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.257122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-13T22:05:30.293426Z","caller":"traceutil/trace.go:171","msg":"trace[1667729339] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1153; }","duration":"164.505698ms","start":"2026-05-13T22:05:30.128912Z","end":"2026-05-13T22:05:30.293418Z","steps":["trace[1667729339] 'agreement among raft nodes before linearized reading' (duration: 164.283142ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:30.294115Z","caller":"traceutil/trace.go:171","msg":"trace[1757724775] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"139.820429ms","start":"2026-05-13T22:05:30.154285Z","end":"2026-05-13T22:05:30.294106Z","steps":["trace[1757724775] 'process raft request' (duration: 139.766448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:32.636885Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.318288794s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-13T22:05:33.619513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"982.507567ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-13T22:05:33.619683Z","caller":"traceutil/trace.go:171","msg":"trace[511239779] linearizableReadLoop","detail":"{readStateIndex:1206; appliedIndex:1205; }","duration":"1.323252079s","start":"2026-05-13T22:05:32.296417Z","end":"2026-05-13T22:05:33.619669Z","steps":["trace[511239779] 'read index received' (duration: 340.668269ms)","trace[511239779] 'applied index is now lower than readState.Index' (duration: 982.58297ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T22:05:33.620044Z","caller":"traceutil/trace.go:171","msg":"trace[1717628295] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"1.321921446s","start":"2026-05-13T22:05:32.298098Z","end":"2026-05-13T22:05:33.62002Z","steps":["trace[1717628295] 'process raft request' (duration: 1.321502465s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:33.620175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:32.298084Z","time spent":"1.322018008s","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T22:05:33.623093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.570684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:05:33.623124Z","caller":"traceutil/trace.go:171","msg":"trace[2039979749] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1160; }","duration":"979.651516ms","start":"2026-05-13T22:05:32.643463Z","end":"2026-05-13T22:05:33.623115Z","steps":["trace[2039979749] 'agreement among raft nodes before linearized reading' (duration: 976.650841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:33.623149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:32.643434Z","time spent":"979.709088ms","remote":"127.0.0.1:52244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-13T22:05:33.623404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.138889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-13T22:05:33.623425Z","caller":"traceutil/trace.go:171","msg":"trace[142060147] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1160; }","duration":"994.184741ms","start":"2026-05-13T22:05:32.629234Z","end":"2026-05-13T22:05:33.623419Z","steps":["trace[142060147] 'agreement among raft nodes before linearized reading' (duration: 990.752955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:33.623442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:32.629219Z","time spent":"994.218571ms","remote":"127.0.0.1:52556","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-05-13T22:05:33.623622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.327201308s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-13T22:05:33.62364Z","caller":"traceutil/trace.go:171","msg":"trace[447124451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1160; }","duration":"1.327252949s","start":"2026-05-13T22:05:32.296382Z","end":"2026-05-13T22:05:33.623635Z","steps":["trace[447124451] 'agreement among raft nodes before linearized reading' (duration: 1.323548196s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:33.623659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:32.296365Z","time spent":"1.327289561s","remote":"127.0.0.1:52556","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-05-13T22:05:33.623755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.128449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-13T22:05:33.623771Z","caller":"traceutil/trace.go:171","msg":"trace[581725067] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1160; }","duration":"837.18362ms","start":"2026-05-13T22:05:32.786583Z","end":"2026-05-13T22:05:33.623766Z","steps":["trace[581725067] 'agreement among raft nodes before linearized reading' (duration: 833.489028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:33.623787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:32.786562Z","time spent":"837.220562ms","remote":"127.0.0.1:52556","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-05-13T22:05:33.623858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.726732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:05:33.623873Z","caller":"traceutil/trace.go:171","msg":"trace[1351025123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1160; }","duration":"456.767873ms","start":"2026-05-13T22:05:33.1671Z","end":"2026-05-13T22:05:33.623868Z","steps":["trace[1351025123] 'agreement among raft nodes before linearized reading' (duration: 452.984828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:33.623888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:33.167085Z","time spent":"456.799534ms","remote":"127.0.0.1:52246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-13T22:05:33.623989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.862481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-13T22:05:33.624007Z","caller":"traceutil/trace.go:171","msg":"trace[1913091821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1160; }","duration":"820.879821ms","start":"2026-05-13T22:05:32.803122Z","end":"2026-05-13T22:05:33.624002Z","steps":["trace[1913091821] 'agreement among raft nodes before linearized reading' (duration: 816.975843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:33.624021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:32.803071Z","time spent":"820.947023ms","remote":"127.0.0.1:52556","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-05-13T22:05:33.624117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.493434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-13T22:05:33.624134Z","caller":"traceutil/trace.go:171","msg":"trace[55599622] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1160; }","duration":"903.530725ms","start":"2026-05-13T22:05:32.720598Z","end":"2026-05-13T22:05:33.624129Z","steps":["trace[55599622] 'agreement among raft nodes before linearized reading' (duration: 899.507965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:33.624149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:32.720585Z","time spent":"903.560286ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-13T22:05:45.939424Z","caller":"traceutil/trace.go:171","msg":"trace[1094779803] linearizableReadLoop","detail":"{readStateIndex:1248; appliedIndex:1247; }","duration":"248.117147ms","start":"2026-05-13T22:05:45.69128Z","end":"2026-05-13T22:05:45.939397Z","steps":["trace[1094779803] 'read index received' (duration: 247.853769ms)","trace[1094779803] 'applied index is now lower than readState.Index' (duration: 262.048µs)"],"step_count":2} {"level":"info","ts":"2026-05-13T22:05:45.93946Z","caller":"traceutil/trace.go:171","msg":"trace[312724788] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"286.988144ms","start":"2026-05-13T22:05:45.652439Z","end":"2026-05-13T22:05:45.939427Z","steps":["trace[312724788] 'process raft request' (duration: 286.768897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:45.939601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.300332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-13T22:05:45.93965Z","caller":"traceutil/trace.go:171","msg":"trace[1134083013] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1199; }","duration":"248.398054ms","start":"2026-05-13T22:05:45.69124Z","end":"2026-05-13T22:05:45.939638Z","steps":["trace[1134083013] 'agreement among raft nodes before linearized reading' (duration: 248.23995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:45.939693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.191639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-13T22:05:45.939753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.46929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-13T22:05:45.939768Z","caller":"traceutil/trace.go:171","msg":"trace[188675055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1199; }","duration":"243.530302ms","start":"2026-05-13T22:05:45.696233Z","end":"2026-05-13T22:05:45.939763Z","steps":["trace[188675055] 'agreement among raft nodes before linearized reading' (duration: 243.489531ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:45.939758Z","caller":"traceutil/trace.go:171","msg":"trace[1025068878] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1199; }","duration":"248.269501ms","start":"2026-05-13T22:05:45.691454Z","end":"2026-05-13T22:05:45.939724Z","steps":["trace[1025068878] 'agreement among raft nodes before linearized reading' (duration: 248.105197ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:45.939828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.33823ms","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":"info","ts":"2026-05-13T22:05:45.939843Z","caller":"traceutil/trace.go:171","msg":"trace[727851168] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1199; }","duration":"220.353191ms","start":"2026-05-13T22:05:45.719483Z","end":"2026-05-13T22:05:45.939836Z","steps":["trace[727851168] 'agreement among raft nodes before linearized reading' (duration: 220.32658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:45.939844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.565542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-13T22:05:45.939896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.249646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-13T22:05:45.939897Z","caller":"traceutil/trace.go:171","msg":"trace[996986368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1199; }","duration":"237.651155ms","start":"2026-05-13T22:05:45.70223Z","end":"2026-05-13T22:05:45.939881Z","steps":["trace[996986368] 'agreement among raft nodes before linearized reading' (duration: 237.46122ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:45.939922Z","caller":"traceutil/trace.go:171","msg":"trace[894499508] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1199; }","duration":"247.310428ms","start":"2026-05-13T22:05:45.692603Z","end":"2026-05-13T22:05:45.939914Z","steps":["trace[894499508] 'agreement among raft nodes before linearized reading' (duration: 247.233836ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:47.999923Z","caller":"traceutil/trace.go:171","msg":"trace[1424315307] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"136.835894ms","start":"2026-05-13T22:05:47.863064Z","end":"2026-05-13T22:05:47.999899Z","steps":["trace[1424315307] 'process raft request' (duration: 136.727132ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:48.192049Z","caller":"traceutil/trace.go:171","msg":"trace[792118038] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"179.471204ms","start":"2026-05-13T22:05:48.012516Z","end":"2026-05-13T22:05:48.191987Z","steps":["trace[792118038] 'process raft request' (duration: 179.207577ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:48.205759Z","caller":"traceutil/trace.go:171","msg":"trace[1584346142] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"192.612025ms","start":"2026-05-13T22:05:48.013121Z","end":"2026-05-13T22:05:48.205733Z","steps":["trace[1584346142] 'process raft request' (duration: 192.489391ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:48.20595Z","caller":"traceutil/trace.go:171","msg":"trace[837800981] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"192.788478ms","start":"2026-05-13T22:05:48.012891Z","end":"2026-05-13T22:05:48.205679Z","steps":["trace[837800981] 'process raft request' (duration: 192.579952ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:48.20603Z","caller":"traceutil/trace.go:171","msg":"trace[1485661856] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"192.83519ms","start":"2026-05-13T22:05:48.013184Z","end":"2026-05-13T22:05:48.206019Z","steps":["trace[1485661856] 'process raft request' (duration: 192.473821ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:48.206321Z","caller":"traceutil/trace.go:171","msg":"trace[348141651] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"192.966003ms","start":"2026-05-13T22:05:48.013346Z","end":"2026-05-13T22:05:48.206312Z","steps":["trace[348141651] 'process raft request' (duration: 192.346817ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:48.20638Z","caller":"traceutil/trace.go:171","msg":"trace[655635331] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"192.070201ms","start":"2026-05-13T22:05:48.014282Z","end":"2026-05-13T22:05:48.206353Z","steps":["trace[655635331] 'process raft request' (duration: 191.876996ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:48.378438Z","caller":"traceutil/trace.go:171","msg":"trace[558570796] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"106.913843ms","start":"2026-05-13T22:05:48.271506Z","end":"2026-05-13T22:05:48.37842Z","steps":["trace[558570796] 'process raft request' (duration: 106.79576ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:49.439487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.974058ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-13T22:05:49.4396Z","caller":"traceutil/trace.go:171","msg":"trace[567384448] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"1.053979691s","start":"2026-05-13T22:05:48.385597Z","end":"2026-05-13T22:05:49.439577Z","steps":["trace[567384448] 'process raft request' (duration: 606.824128ms)","trace[567384448] 'compare' (duration: 446.758873ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T22:05:49.43966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:48.38558Z","time spent":"1.054051252s","remote":"127.0.0.1:52568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T22:05:49.99164Z","caller":"traceutil/trace.go:171","msg":"trace[1081965464] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"1.603813599s","start":"2026-05-13T22:05:48.387805Z","end":"2026-05-13T22:05:49.991619Z","steps":["trace[1081965464] 'process raft request' (duration: 1.603659965s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:49.991779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:48.387786Z","time spent":"1.603925622s","remote":"127.0.0.1:52432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":915,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T22:05:49.992054Z","caller":"traceutil/trace.go:171","msg":"trace[460007388] linearizableReadLoop","detail":"{readStateIndex:1281; appliedIndex:1278; }","duration":"1.430601852s","start":"2026-05-13T22:05:48.561424Z","end":"2026-05-13T22:05:49.992026Z","steps":["trace[460007388] 'read index received' (duration: 430.947134ms)","trace[460007388] 'applied index is now lower than readState.Index' (duration: 999.652248ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T22:05:49.992117Z","caller":"traceutil/trace.go:171","msg":"trace[959072593] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"1.602935367s","start":"2026-05-13T22:05:48.38917Z","end":"2026-05-13T22:05:49.992105Z","steps":["trace[959072593] 'process raft request' (duration: 1.602394704s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:49.99218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:48.389145Z","time spent":"1.603002219s","remote":"127.0.0.1:52764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T22:05:49.992352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.430907549s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:05:49.99245Z","caller":"traceutil/trace.go:171","msg":"trace[1792501430] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1231; }","duration":"1.430980651s","start":"2026-05-13T22:05:48.56141Z","end":"2026-05-13T22:05:49.99239Z","steps":["trace[1792501430] 'agreement among raft nodes before linearized reading' (duration: 1.430856828s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:49.9925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:48.561369Z","time spent":"1.431117404s","remote":"127.0.0.1:52408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-05-13T22:05:50.342086Z","caller":"traceutil/trace.go:171","msg":"trace[88595546] linearizableReadLoop","detail":"{readStateIndex:1282; appliedIndex:1281; }","duration":"349.935362ms","start":"2026-05-13T22:05:49.992123Z","end":"2026-05-13T22:05:50.342058Z","steps":["trace[88595546] 'read index received' (duration: 349.552103ms)","trace[88595546] 'applied index is now lower than readState.Index' (duration: 382.179µs)"],"step_count":2} {"level":"warn","ts":"2026-05-13T22:05:50.342207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.524916179s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-13T22:05:50.342209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.175287744s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:05:50.342304Z","caller":"traceutil/trace.go:171","msg":"trace[1510251132] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1232; }","duration":"1.175423308s","start":"2026-05-13T22:05:49.166855Z","end":"2026-05-13T22:05:50.342279Z","steps":["trace[1510251132] 'agreement among raft nodes before linearized reading' (duration: 1.175221713s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:50.342272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.291474481s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-13T22:05:50.34237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:49.166839Z","time spent":"1.17552154s","remote":"127.0.0.1:52244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-13T22:05:50.342408Z","caller":"traceutil/trace.go:171","msg":"trace[858741631] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1232; }","duration":"1.291674306s","start":"2026-05-13T22:05:49.050704Z","end":"2026-05-13T22:05:50.342379Z","steps":["trace[858741631] 'agreement among raft nodes before linearized reading' (duration: 1.29140346s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:50.342416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.536940481s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-13T22:05:50.342416Z","caller":"traceutil/trace.go:171","msg":"trace[719448338] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1232; }","duration":"1.525043853s","start":"2026-05-13T22:05:48.817219Z","end":"2026-05-13T22:05:50.342263Z","steps":["trace[719448338] 'agreement among raft nodes before linearized reading' (duration: 1.524897909s)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:50.342415Z","caller":"traceutil/trace.go:171","msg":"trace[1062897705] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"1.246737479s","start":"2026-05-13T22:05:49.095649Z","end":"2026-05-13T22:05:50.342386Z","steps":["trace[1062897705] 'process raft request' (duration: 1.24599404s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:50.342464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:49.050685Z","time spent":"1.291766178s","remote":"127.0.0.1:52522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-05-13T22:05:50.342483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:48.817201Z","time spent":"1.525266628s","remote":"127.0.0.1:52650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-05-13T22:05:50.342491Z","caller":"traceutil/trace.go:171","msg":"trace[1767070585] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1232; }","duration":"1.537117055s","start":"2026-05-13T22:05:48.805358Z","end":"2026-05-13T22:05:50.342476Z","steps":["trace[1767070585] 'agreement among raft nodes before linearized reading' (duration: 1.536900839s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:50.342547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:49.095621Z","time spent":"1.246854571s","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T22:05:50.34259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:48.805338Z","time spent":"1.537185787s","remote":"127.0.0.1:52662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-05-13T22:05:50.613082Z","caller":"traceutil/trace.go:171","msg":"trace[1796773233] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"600.86121ms","start":"2026-05-13T22:05:50.012203Z","end":"2026-05-13T22:05:50.613064Z","steps":["trace[1796773233] 'process raft request' (duration: 600.714536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:50.613193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:50.012181Z","time spent":"600.955822ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T22:05:50.613277Z","caller":"traceutil/trace.go:171","msg":"trace[141351460] linearizableReadLoop","detail":"{readStateIndex:1284; appliedIndex:1282; }","duration":"271.015972ms","start":"2026-05-13T22:05:50.34223Z","end":"2026-05-13T22:05:50.613246Z","steps":["trace[141351460] 'read index received' (duration: 270.616852ms)","trace[141351460] 'applied index is now lower than readState.Index' (duration: 396.949µs)"],"step_count":2} {"level":"info","ts":"2026-05-13T22:05:50.613368Z","caller":"traceutil/trace.go:171","msg":"trace[1699879114] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"592.15968ms","start":"2026-05-13T22:05:50.021202Z","end":"2026-05-13T22:05:50.613362Z","steps":["trace[1699879114] 'process raft request' (duration: 591.826842ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:50.613394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:50.021185Z","time spent":"592.197032ms","remote":"127.0.0.1:52440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5883,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T22:05:50.613481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.542613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-13T22:05:50.6135Z","caller":"traceutil/trace.go:171","msg":"trace[592666269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1234; }","duration":"408.585014ms","start":"2026-05-13T22:05:50.204911Z","end":"2026-05-13T22:05:50.613496Z","steps":["trace[592666269] 'agreement among raft nodes before linearized reading' (duration: 408.539773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:50.613515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:50.204897Z","time spent":"408.614455ms","remote":"127.0.0.1:52556","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-05-13T22:05:50.613539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.896203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:05:50.61359Z","caller":"traceutil/trace.go:171","msg":"trace[889365595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1234; }","duration":"269.947905ms","start":"2026-05-13T22:05:50.34363Z","end":"2026-05-13T22:05:50.613578Z","steps":["trace[889365595] 'agreement among raft nodes before linearized reading' (duration: 269.875383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:50.613619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.267736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-13T22:05:50.613632Z","caller":"traceutil/trace.go:171","msg":"trace[332284470] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1234; }","duration":"404.320547ms","start":"2026-05-13T22:05:50.209308Z","end":"2026-05-13T22:05:50.613628Z","steps":["trace[332284470] 'agreement among raft nodes before linearized reading' (duration: 404.280316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:50.613347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.178169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-13T22:05:50.613649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:50.20928Z","time spent":"404.366407ms","remote":"127.0.0.1:52556","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-05-13T22:05:50.61363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.552667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-13T22:05:50.613669Z","caller":"traceutil/trace.go:171","msg":"trace[736771631] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1234; }","duration":"403.548418ms","start":"2026-05-13T22:05:50.210107Z","end":"2026-05-13T22:05:50.613655Z","steps":["trace[736771631] 'agreement among raft nodes before linearized reading' (duration: 403.166968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:50.613723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:50.210086Z","time spent":"403.624919ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-13T22:05:50.613717Z","caller":"traceutil/trace.go:171","msg":"trace[370743720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1234; }","duration":"403.63404ms","start":"2026-05-13T22:05:50.210037Z","end":"2026-05-13T22:05:50.613671Z","steps":["trace[370743720] 'agreement among raft nodes before linearized reading' (duration: 403.377364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:50.61378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:50.210031Z","time spent":"403.734732ms","remote":"127.0.0.1:52556","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-05-13T22:05:50.613733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.428359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-13T22:05:50.613871Z","caller":"traceutil/trace.go:171","msg":"trace[154227818] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1234; }","duration":"404.584924ms","start":"2026-05-13T22:05:50.209271Z","end":"2026-05-13T22:05:50.613856Z","steps":["trace[154227818] 'agreement among raft nodes before linearized reading' (duration: 404.4327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:50.614015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:50.209254Z","time spent":"404.720707ms","remote":"127.0.0.1:52556","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-05-13T22:05:50.845489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.676825ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-13T22:05:50.845623Z","caller":"traceutil/trace.go:171","msg":"trace[1173946181] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"225.414018ms","start":"2026-05-13T22:05:50.620187Z","end":"2026-05-13T22:05:50.845601Z","steps":["trace[1173946181] 'process raft request' (duration: 97.542388ms)","trace[1173946181] 'compare' (duration: 127.580243ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T22:05:50.874243Z","caller":"traceutil/trace.go:171","msg":"trace[1963874663] linearizableReadLoop","detail":"{readStateIndex:1289; appliedIndex:1284; }","duration":"248.017724ms","start":"2026-05-13T22:05:50.626202Z","end":"2026-05-13T22:05:50.87422Z","steps":["trace[1963874663] 'read index received' (duration: 91.646369ms)","trace[1963874663] 'applied index is now lower than readState.Index' (duration: 156.370775ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T22:05:50.874281Z","caller":"traceutil/trace.go:171","msg":"trace[23497480] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"253.399029ms","start":"2026-05-13T22:05:50.620825Z","end":"2026-05-13T22:05:50.874224Z","steps":["trace[23497480] 'process raft request' (duration: 253.274806ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:50.874339Z","caller":"traceutil/trace.go:171","msg":"trace[100237967] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"253.426639ms","start":"2026-05-13T22:05:50.620893Z","end":"2026-05-13T22:05:50.87432Z","steps":["trace[100237967] 'process raft request' (duration: 253.260085ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:50.874337Z","caller":"traceutil/trace.go:171","msg":"trace[1975792070] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"252.276972ms","start":"2026-05-13T22:05:50.622018Z","end":"2026-05-13T22:05:50.874295Z","steps":["trace[1975792070] 'process raft request' (duration: 252.156889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:50.874424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.216369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:05:50.874458Z","caller":"traceutil/trace.go:171","msg":"trace[524260684] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1239; }","duration":"248.271811ms","start":"2026-05-13T22:05:50.626181Z","end":"2026-05-13T22:05:50.874452Z","steps":["trace[524260684] 'agreement among raft nodes before linearized reading' (duration: 248.220249ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:50.874363Z","caller":"traceutil/trace.go:171","msg":"trace[89602113] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"251.544723ms","start":"2026-05-13T22:05:50.6228Z","end":"2026-05-13T22:05:50.874345Z","steps":["trace[89602113] 'process raft request' (duration: 251.398369ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:51.027281Z","caller":"traceutil/trace.go:171","msg":"trace[1227990256] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"162.033017ms","start":"2026-05-13T22:05:50.86522Z","end":"2026-05-13T22:05:51.027253Z","steps":["trace[1227990256] 'process raft request' (duration: 96.576193ms)","trace[1227990256] 'compare' (duration: 65.342701ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T22:05:51.488261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.585836ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-13T22:05:51.488453Z","caller":"traceutil/trace.go:171","msg":"trace[1211797065] linearizableReadLoop","detail":"{readStateIndex:1291; appliedIndex:1290; }","duration":"334.667768ms","start":"2026-05-13T22:05:51.153767Z","end":"2026-05-13T22:05:51.488435Z","steps":["trace[1211797065] 'read index received' (duration: 163.032781ms)","trace[1211797065] 'applied index is now lower than readState.Index' (duration: 171.633847ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T22:05:51.488575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.800512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-13T22:05:51.488601Z","caller":"traceutil/trace.go:171","msg":"trace[1516801533] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1240; }","duration":"334.869394ms","start":"2026-05-13T22:05:51.153723Z","end":"2026-05-13T22:05:51.488592Z","steps":["trace[1516801533] 'agreement among raft nodes before linearized reading' (duration: 334.806663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:51.488635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:51.153706Z","time spent":"334.920725ms","remote":"127.0.0.1:52634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":29,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-05-13T22:05:51.488767Z","caller":"traceutil/trace.go:171","msg":"trace[1952950330] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"218.990546ms","start":"2026-05-13T22:05:51.269758Z","end":"2026-05-13T22:05:51.488748Z","steps":["trace[1952950330] 'process raft request' (duration: 218.597896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:52.449456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.115612608s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:05:52.449524Z","caller":"traceutil/trace.go:171","msg":"trace[291510149] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1241; }","duration":"1.11572392s","start":"2026-05-13T22:05:51.333782Z","end":"2026-05-13T22:05:52.449506Z","steps":["trace[291510149] 'agreement among raft nodes before linearized reading' (duration: 154.944768ms)","trace[291510149] 'count revisions from in-memory index tree' (duration: 960.680649ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T22:05:52.449554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:51.333764Z","time spent":"1.115782451s","remote":"127.0.0.1:54528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-13T22:05:52.449692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.259339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:05:52.449712Z","caller":"traceutil/trace.go:171","msg":"trace[1832022363] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1241; }","duration":"950.30733ms","start":"2026-05-13T22:05:51.499398Z","end":"2026-05-13T22:05:52.449705Z","steps":["trace[1832022363] 'count revisions from in-memory index tree' (duration: 950.169747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:52.449732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:51.49934Z","time spent":"950.385782ms","remote":"127.0.0.1:40182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-05-13T22:05:52.449823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.384863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:05:52.449891Z","caller":"traceutil/trace.go:171","msg":"trace[769340474] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1241; }","duration":"283.481994ms","start":"2026-05-13T22:05:52.16639Z","end":"2026-05-13T22:05:52.449872Z","steps":["trace[769340474] 'range keys from in-memory index tree' (duration: 283.329501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:53.350259Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880869378639850894,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-13T22:05:53.523599Z","caller":"traceutil/trace.go:171","msg":"trace[394744698] linearizableReadLoop","detail":"{readStateIndex:1293; appliedIndex:1292; }","duration":"673.852202ms","start":"2026-05-13T22:05:52.849723Z","end":"2026-05-13T22:05:53.523575Z","steps":["trace[394744698] 'read index received' (duration: 673.590576ms)","trace[394744698] 'applied index is now lower than readState.Index' (duration: 260.866µs)"],"step_count":2} {"level":"info","ts":"2026-05-13T22:05:53.523648Z","caller":"traceutil/trace.go:171","msg":"trace[1127159551] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"900.348326ms","start":"2026-05-13T22:05:52.62327Z","end":"2026-05-13T22:05:53.523618Z","steps":["trace[1127159551] 'process raft request' (duration: 900.154662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:53.523781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:52.623245Z","time spent":"900.46683ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T22:05:53.523858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.122369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-13T22:05:53.523901Z","caller":"traceutil/trace.go:171","msg":"trace[1018962] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1242; }","duration":"674.222311ms","start":"2026-05-13T22:05:52.849667Z","end":"2026-05-13T22:05:53.523889Z","steps":["trace[1018962] 'agreement among raft nodes before linearized reading' (duration: 674.037917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:53.523946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:52.849646Z","time spent":"674.292032ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-13T22:05:53.523988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.978446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-13T22:05:53.523887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.254623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-13T22:05:53.524051Z","caller":"traceutil/trace.go:171","msg":"trace[1593958761] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1242; }","duration":"645.475419ms","start":"2026-05-13T22:05:52.878567Z","end":"2026-05-13T22:05:53.524043Z","steps":["trace[1593958761] 'agreement among raft nodes before linearized reading' (duration: 645.171851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:53.524082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:52.878545Z","time spent":"645.53069ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-13T22:05:53.524084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.436358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-13T22:05:53.52412Z","caller":"traceutil/trace.go:171","msg":"trace[1873639363] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1242; }","duration":"645.483429ms","start":"2026-05-13T22:05:52.878629Z","end":"2026-05-13T22:05:53.524112Z","steps":["trace[1873639363] 'agreement among raft nodes before linearized reading' (duration: 645.388926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:53.523993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.899135ms","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-05-13T22:05:53.524146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:52.878623Z","time spent":"645.518089ms","remote":"127.0.0.1:52556","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":"info","ts":"2026-05-13T22:05:53.52415Z","caller":"traceutil/trace.go:171","msg":"trace[527043658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1242; }","duration":"645.094179ms","start":"2026-05-13T22:05:52.879049Z","end":"2026-05-13T22:05:53.524144Z","steps":["trace[527043658] 'agreement among raft nodes before linearized reading' (duration: 644.835983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:53.524168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:52.87903Z","time spent":"645.13363ms","remote":"127.0.0.1:52556","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":"info","ts":"2026-05-13T22:05:53.524045Z","caller":"traceutil/trace.go:171","msg":"trace[378868052] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1242; }","duration":"645.040658ms","start":"2026-05-13T22:05:52.878987Z","end":"2026-05-13T22:05:53.524027Z","steps":["trace[378868052] 'agreement among raft nodes before linearized reading' (duration: 644.942195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:53.524209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:52.878978Z","time spent":"645.226323ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-13T22:05:53.52399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.110891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:05:53.524264Z","caller":"traceutil/trace.go:171","msg":"trace[1959376629] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1242; }","duration":"357.42503ms","start":"2026-05-13T22:05:53.166832Z","end":"2026-05-13T22:05:53.524258Z","steps":["trace[1959376629] 'agreement among raft nodes before linearized reading' (duration: 357.112743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:53.52428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:53.166812Z","time spent":"357.465142ms","remote":"127.0.0.1:52244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-13T22:05:53.721129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.381778ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-13T22:05:53.721249Z","caller":"traceutil/trace.go:171","msg":"trace[1228608144] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"192.978872ms","start":"2026-05-13T22:05:53.528248Z","end":"2026-05-13T22:05:53.721227Z","steps":["trace[1228608144] 'process raft request' (duration: 51.4058ms)","trace[1228608144] 'compare' (duration: 141.225873ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T22:05:53.798446Z","caller":"traceutil/trace.go:171","msg":"trace[692127200] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"268.033297ms","start":"2026-05-13T22:05:53.530392Z","end":"2026-05-13T22:05:53.798426Z","steps":["trace[692127200] 'process raft request' (duration: 267.982326ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:53.79851Z","caller":"traceutil/trace.go:171","msg":"trace[1881547827] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"268.092548ms","start":"2026-05-13T22:05:53.530377Z","end":"2026-05-13T22:05:53.79847Z","steps":["trace[1881547827] 'process raft request' (duration: 267.886973ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:53.798687Z","caller":"traceutil/trace.go:171","msg":"trace[1438284254] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"268.304364ms","start":"2026-05-13T22:05:53.530371Z","end":"2026-05-13T22:05:53.798676Z","steps":["trace[1438284254] 'process raft request' (duration: 268.027637ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:53.7987Z","caller":"traceutil/trace.go:171","msg":"trace[1257400980] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"268.073738ms","start":"2026-05-13T22:05:53.530608Z","end":"2026-05-13T22:05:53.798681Z","steps":["trace[1257400980] 'process raft request' (duration: 267.984016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:54.890226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.092769ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-13T22:05:54.890385Z","caller":"traceutil/trace.go:171","msg":"trace[1469334474] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"687.214588ms","start":"2026-05-13T22:05:54.203154Z","end":"2026-05-13T22:05:54.890368Z","steps":["trace[1469334474] 'process raft request' (duration: 687.170997ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:54.890374Z","caller":"traceutil/trace.go:171","msg":"trace[705121272] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"732.114604ms","start":"2026-05-13T22:05:54.158222Z","end":"2026-05-13T22:05:54.890337Z","steps":["trace[705121272] 'process raft request' (duration: 143.844201ms)","trace[705121272] 'compare' (duration: 587.961915ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T22:05:54.89046Z","caller":"traceutil/trace.go:171","msg":"trace[70120838] linearizableReadLoop","detail":"{readStateIndex:1301; appliedIndex:1300; }","duration":"689.389771ms","start":"2026-05-13T22:05:54.201046Z","end":"2026-05-13T22:05:54.890436Z","steps":["trace[70120838] 'read index received' (duration: 101.056507ms)","trace[70120838] 'applied index is now lower than readState.Index' (duration: 588.330944ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T22:05:54.890517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:54.203134Z","time spent":"687.283069ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-13T22:05:54.890505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:54.158197Z","time spent":"732.238986ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-13T22:05:54.890686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.622937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-05-13T22:05:54.890722Z","caller":"traceutil/trace.go:171","msg":"trace[1396504027] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1251; }","duration":"689.703539ms","start":"2026-05-13T22:05:54.201006Z","end":"2026-05-13T22:05:54.89071Z","steps":["trace[1396504027] 'agreement among raft nodes before linearized reading' (duration: 689.520005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:54.890769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:54.200989Z","time spent":"689.77313ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":494,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-13T22:05:55.577068Z","caller":"traceutil/trace.go:171","msg":"trace[1131399488] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"682.650403ms","start":"2026-05-13T22:05:54.8944Z","end":"2026-05-13T22:05:55.57705Z","steps":["trace[1131399488] 'process raft request' (duration: 682.55408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:55.577175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:54.894371Z","time spent":"682.749545ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-13T22:05:56.005822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.781577ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-13T22:05:56.005989Z","caller":"traceutil/trace.go:171","msg":"trace[371074447] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1303; }","duration":"839.402686ms","start":"2026-05-13T22:05:55.166576Z","end":"2026-05-13T22:05:56.005979Z","steps":["trace[371074447] 'read index received' (duration: 410.683407ms)","trace[371074447] 'applied index is now lower than readState.Index' (duration: 428.718629ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T22:05:56.006057Z","caller":"traceutil/trace.go:171","msg":"trace[1561528357] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"1.110243923s","start":"2026-05-13T22:05:54.895804Z","end":"2026-05-13T22:05:56.006048Z","steps":["trace[1561528357] 'process raft request' (duration: 727.179579ms)","trace[1561528357] 'compare' (duration: 365.114044ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T22:05:56.006118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.53323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-13T22:05:56.006103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:54.895792Z","time spent":"1.110286214s","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T22:05:56.006196Z","caller":"traceutil/trace.go:171","msg":"trace[604993672] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1254; }","duration":"839.653792ms","start":"2026-05-13T22:05:55.166525Z","end":"2026-05-13T22:05:56.006179Z","steps":["trace[604993672] 'agreement among raft nodes before linearized reading' (duration: 839.520049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:56.006244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:55.166505Z","time spent":"839.728574ms","remote":"127.0.0.1:52244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-13T22:05:56.006294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.3341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:05:56.006325Z","caller":"traceutil/trace.go:171","msg":"trace[1237700904] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1254; }","duration":"689.401511ms","start":"2026-05-13T22:05:55.316916Z","end":"2026-05-13T22:05:56.006317Z","steps":["trace[1237700904] 'agreement among raft nodes before linearized reading' (duration: 689.34247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:56.00635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:55.316896Z","time spent":"689.446992ms","remote":"127.0.0.1:52580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-13T22:05:56.006472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.555444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-13T22:05:56.006505Z","caller":"traceutil/trace.go:171","msg":"trace[195517397] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1254; }","duration":"204.612625ms","start":"2026-05-13T22:05:55.801886Z","end":"2026-05-13T22:05:56.006498Z","steps":["trace[195517397] 'agreement among raft nodes before linearized reading' (duration: 204.514013ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:56.006519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.007151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:05:56.006532Z","caller":"traceutil/trace.go:171","msg":"trace[1219807966] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"477.271967ms","start":"2026-05-13T22:05:55.529244Z","end":"2026-05-13T22:05:56.006516Z","steps":["trace[1219807966] 'process raft request' (duration: 476.641442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:56.006598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.235407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-13T22:05:56.006619Z","caller":"traceutil/trace.go:171","msg":"trace[358193088] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1254; }","duration":"281.288849ms","start":"2026-05-13T22:05:55.725325Z","end":"2026-05-13T22:05:56.006613Z","steps":["trace[358193088] 'agreement among raft nodes before linearized reading' (duration: 281.231907ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:56.006632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:05:55.529223Z","time spent":"477.341219ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T22:05:56.006747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.283921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-13T22:05:56.00677Z","caller":"traceutil/trace.go:171","msg":"trace[770210348] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1254; }","duration":"203.319582ms","start":"2026-05-13T22:05:55.803445Z","end":"2026-05-13T22:05:56.006764Z","steps":["trace[770210348] 'agreement among raft nodes before linearized reading' (duration: 203.26147ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:56.006545Z","caller":"traceutil/trace.go:171","msg":"trace[1362700153] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1254; }","duration":"228.069103ms","start":"2026-05-13T22:05:55.778468Z","end":"2026-05-13T22:05:56.006538Z","steps":["trace[1362700153] 'agreement among raft nodes before linearized reading' (duration: 228.028612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:56.006871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.730878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-13T22:05:56.006891Z","caller":"traceutil/trace.go:171","msg":"trace[538708025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1254; }","duration":"204.751738ms","start":"2026-05-13T22:05:55.802133Z","end":"2026-05-13T22:05:56.006884Z","steps":["trace[538708025] 'agreement among raft nodes before linearized reading' (duration: 204.680887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:56.006485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.687067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-13T22:05:56.006924Z","caller":"traceutil/trace.go:171","msg":"trace[512661965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1254; }","duration":"205.124267ms","start":"2026-05-13T22:05:55.801792Z","end":"2026-05-13T22:05:56.006916Z","steps":["trace[512661965] 'agreement among raft nodes before linearized reading' (duration: 204.651626ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:56.277624Z","caller":"traceutil/trace.go:171","msg":"trace[1094610671] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"266.771535ms","start":"2026-05-13T22:05:56.010818Z","end":"2026-05-13T22:05:56.27759Z","steps":["trace[1094610671] 'process raft request' (duration: 266.526519ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:56.277916Z","caller":"traceutil/trace.go:171","msg":"trace[1691947596] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"266.99611ms","start":"2026-05-13T22:05:56.010885Z","end":"2026-05-13T22:05:56.277881Z","steps":["trace[1691947596] 'process raft request' (duration: 266.647821ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:56.278268Z","caller":"traceutil/trace.go:171","msg":"trace[400322511] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"267.306008ms","start":"2026-05-13T22:05:56.010937Z","end":"2026-05-13T22:05:56.278243Z","steps":["trace[400322511] 'process raft request' (duration: 266.837196ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:56.278457Z","caller":"traceutil/trace.go:171","msg":"trace[900958370] linearizableReadLoop","detail":"{readStateIndex:1311; appliedIndex:1306; }","duration":"111.088678ms","start":"2026-05-13T22:05:56.167349Z","end":"2026-05-13T22:05:56.278438Z","steps":["trace[900958370] 'read index received' (duration: 110.045392ms)","trace[900958370] 'applied index is now lower than readState.Index' (duration: 1.042196ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T22:05:56.278542Z","caller":"traceutil/trace.go:171","msg":"trace[835091017] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"267.223206ms","start":"2026-05-13T22:05:56.011306Z","end":"2026-05-13T22:05:56.278529Z","steps":["trace[835091017] 'process raft request' (duration: 266.825036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:56.278578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.20585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:05:56.278638Z","caller":"traceutil/trace.go:171","msg":"trace[21906253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1259; }","duration":"111.313333ms","start":"2026-05-13T22:05:56.167305Z","end":"2026-05-13T22:05:56.278618Z","steps":["trace[21906253] 'agreement among raft nodes before linearized reading' (duration: 111.214231ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:05:56.278668Z","caller":"traceutil/trace.go:171","msg":"trace[1011350830] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"267.140804ms","start":"2026-05-13T22:05:56.011514Z","end":"2026-05-13T22:05:56.278655Z","steps":["trace[1011350830] 'process raft request' (duration: 266.854497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:05:56.429315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.230481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-13T22:05:56.42945Z","caller":"traceutil/trace.go:171","msg":"trace[139349208] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"214.631316ms","start":"2026-05-13T22:05:56.214792Z","end":"2026-05-13T22:05:56.429424Z","steps":["trace[139349208] 'process raft request' (duration: 101.18814ms)","trace[139349208] 'compare' (duration: 113.081727ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T22:06:04.876896Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880869378639851029,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-13T22:06:05.180059Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.159246133s","expected-duration":"1s"} {"level":"info","ts":"2026-05-13T22:06:05.180492Z","caller":"traceutil/trace.go:171","msg":"trace[860102836] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"1.159779886s","start":"2026-05-13T22:06:04.020696Z","end":"2026-05-13T22:06:05.180476Z","steps":["trace[860102836] 'process raft request' (duration: 1.159678764s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:06:05.180591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:06:04.020672Z","time spent":"1.159868369s","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T22:06:05.468909Z","caller":"traceutil/trace.go:171","msg":"trace[767469925] linearizableReadLoop","detail":"{readStateIndex:1371; appliedIndex:1370; }","duration":"1.092671782s","start":"2026-05-13T22:06:04.376209Z","end":"2026-05-13T22:06:05.468881Z","steps":["trace[767469925] 'read index received' (duration: 804.455049ms)","trace[767469925] 'applied index is now lower than readState.Index' (duration: 288.215683ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T22:06:05.469263Z","caller":"traceutil/trace.go:171","msg":"trace[1678031638] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"1.439864125s","start":"2026-05-13T22:06:04.029383Z","end":"2026-05-13T22:06:05.469247Z","steps":["trace[1678031638] 'process raft request' (duration: 1.439232839s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:06:05.469584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.647851ms","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-05-13T22:06:05.469636Z","caller":"traceutil/trace.go:171","msg":"trace[316267767] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1318; }","duration":"631.746733ms","start":"2026-05-13T22:06:04.837877Z","end":"2026-05-13T22:06:05.469624Z","steps":["trace[316267767] 'agreement among raft nodes before linearized reading' (duration: 631.655491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:06:05.469672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:06:04.837854Z","time spent":"631.808975ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-05-13T22:06:05.469774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.329203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-05-13T22:06:05.469897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.472611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:06:05.469876Z","caller":"traceutil/trace.go:171","msg":"trace[2081173981] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1318; }","duration":"152.468296ms","start":"2026-05-13T22:06:05.317391Z","end":"2026-05-13T22:06:05.469859Z","steps":["trace[2081173981] 'agreement among raft nodes before linearized reading' (duration: 152.23577ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:06:05.469931Z","caller":"traceutil/trace.go:171","msg":"trace[1874636500] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1318; }","duration":"302.573843ms","start":"2026-05-13T22:06:05.167348Z","end":"2026-05-13T22:06:05.469922Z","steps":["trace[1874636500] 'agreement among raft nodes before linearized reading' (duration: 302.523731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:06:05.469956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:06:05.167328Z","time spent":"302.622604ms","remote":"127.0.0.1:52244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-13T22:06:05.470027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.084398354s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-13T22:06:05.470105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.896062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:06:05.470117Z","caller":"traceutil/trace.go:171","msg":"trace[350648908] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1318; }","duration":"1.084520547s","start":"2026-05-13T22:06:04.385569Z","end":"2026-05-13T22:06:05.47009Z","steps":["trace[350648908] 'agreement among raft nodes before linearized reading' (duration: 1.084015304s)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:06:05.47014Z","caller":"traceutil/trace.go:171","msg":"trace[1570160984] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1318; }","duration":"465.961753ms","start":"2026-05-13T22:06:05.004164Z","end":"2026-05-13T22:06:05.470125Z","steps":["trace[1570160984] 'agreement among raft nodes before linearized reading' (duration: 465.916592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:06:05.470167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:06:04.38556Z","time spent":"1.084599139s","remote":"127.0.0.1:52556","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-05-13T22:06:05.47018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:06:05.004145Z","time spent":"466.022895ms","remote":"127.0.0.1:40090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-05-13T22:06:05.469591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.087527912s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-13T22:06:05.47053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.094319453s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-13T22:06:05.470568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.031509757s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:06:05.470589Z","caller":"traceutil/trace.go:171","msg":"trace[1117955004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1318; }","duration":"1.094421306s","start":"2026-05-13T22:06:04.376154Z","end":"2026-05-13T22:06:05.470575Z","steps":["trace[1117955004] 'agreement among raft nodes before linearized reading' (duration: 1.094175529s)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:06:05.47061Z","caller":"traceutil/trace.go:171","msg":"trace[1912640358] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1318; }","duration":"1.031567648s","start":"2026-05-13T22:06:04.439028Z","end":"2026-05-13T22:06:05.470595Z","steps":["trace[1912640358] 'agreement among raft nodes before linearized reading' (duration: 1.031501557s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:06:05.470631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:06:04.376134Z","time spent":"1.094485898s","remote":"127.0.0.1:52556","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-05-13T22:06:05.470721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.033584419s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-05-13T22:06:05.470753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086153888s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-13T22:06:05.4708Z","caller":"traceutil/trace.go:171","msg":"trace[1969110247] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1318; }","duration":"1.08623921s","start":"2026-05-13T22:06:04.384545Z","end":"2026-05-13T22:06:05.470784Z","steps":["trace[1969110247] 'agreement among raft nodes before linearized reading' (duration: 1.086073806s)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:06:05.470781Z","caller":"traceutil/trace.go:171","msg":"trace[1272473551] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1318; }","duration":"1.033671001s","start":"2026-05-13T22:06:04.437096Z","end":"2026-05-13T22:06:05.470767Z","steps":["trace[1272473551] 'agreement among raft nodes before linearized reading' (duration: 1.033549658s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:06:05.470841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:06:04.384525Z","time spent":"1.086307972s","remote":"127.0.0.1:52556","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-05-13T22:06:05.470862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.085647736s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-13T22:06:05.470894Z","caller":"traceutil/trace.go:171","msg":"trace[814257493] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1318; }","duration":"1.086011454s","start":"2026-05-13T22:06:04.384874Z","end":"2026-05-13T22:06:05.470885Z","steps":["trace[814257493] 'agreement among raft nodes before linearized reading' (duration: 1.085570173s)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:06:05.470928Z","caller":"traceutil/trace.go:171","msg":"trace[1914015899] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1318; }","duration":"1.088529218s","start":"2026-05-13T22:06:04.382019Z","end":"2026-05-13T22:06:05.470548Z","steps":["trace[1914015899] 'agreement among raft nodes before linearized reading' (duration: 1.08744081s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:06:05.47097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:06:04.437078Z","time spent":"1.033763203s","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-13T22:06:05.471307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:06:04.382006Z","time spent":"1.089280496s","remote":"127.0.0.1:52556","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-05-13T22:06:05.46938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:06:04.029355Z","time spent":"1.439946167s","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T22:06:05.471038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:06:04.439021Z","time spent":"1.031632041s","remote":"127.0.0.1:52582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-05-13T22:06:05.470914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:06:04.384856Z","time spent":"1.086051986s","remote":"127.0.0.1:52556","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":"info","ts":"2026-05-13T22:06:56.392561Z","caller":"traceutil/trace.go:171","msg":"trace[957921377] transaction","detail":"{read_only:false; response_revision:1553; number_of_response:1; }","duration":"338.335469ms","start":"2026-05-13T22:06:56.054192Z","end":"2026-05-13T22:06:56.392527Z","steps":["trace[957921377] 'process raft request' (duration: 337.671852ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:06:56.392591Z","caller":"traceutil/trace.go:171","msg":"trace[1288131159] transaction","detail":"{read_only:false; response_revision:1555; number_of_response:1; }","duration":"337.650012ms","start":"2026-05-13T22:06:56.054917Z","end":"2026-05-13T22:06:56.392567Z","steps":["trace[1288131159] 'process raft request' (duration: 337.58334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:06:56.392731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:06:56.054173Z","time spent":"338.475513ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T22:06:56.392739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:06:56.054896Z","time spent":"337.764035ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T22:06:56.392791Z","caller":"traceutil/trace.go:171","msg":"trace[2108430116] linearizableReadLoop","detail":"{readStateIndex:1620; appliedIndex:1616; }","duration":"270.872653ms","start":"2026-05-13T22:06:56.121901Z","end":"2026-05-13T22:06:56.392773Z","steps":["trace[2108430116] 'read index received' (duration: 270.047932ms)","trace[2108430116] 'applied index is now lower than readState.Index' (duration: 824.141µs)"],"step_count":2} {"level":"info","ts":"2026-05-13T22:06:56.392941Z","caller":"traceutil/trace.go:171","msg":"trace[1104789388] transaction","detail":"{read_only:false; response_revision:1556; number_of_response:1; }","duration":"337.275792ms","start":"2026-05-13T22:06:56.055657Z","end":"2026-05-13T22:06:56.392933Z","steps":["trace[1104789388] 'process raft request' (duration: 336.875112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:06:56.393021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:06:56.055648Z","time spent":"337.317613ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T22:06:56.392994Z","caller":"traceutil/trace.go:171","msg":"trace[1840342410] transaction","detail":"{read_only:false; response_revision:1554; number_of_response:1; }","duration":"338.314578ms","start":"2026-05-13T22:06:56.054631Z","end":"2026-05-13T22:06:56.392945Z","steps":["trace[1840342410] 'process raft request' (duration: 337.811946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:06:56.393165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:06:56.054611Z","time spent":"338.463332ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T22:06:56.393318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.404636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-05-13T22:06:56.393385Z","caller":"traceutil/trace.go:171","msg":"trace[1511288478] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1556; }","duration":"271.54171ms","start":"2026-05-13T22:06:56.121828Z","end":"2026-05-13T22:06:56.39337Z","steps":["trace[1511288478] 'agreement among raft nodes before linearized reading' (duration: 271.344125ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:06:56.460971Z","caller":"traceutil/trace.go:171","msg":"trace[386751127] transaction","detail":"{read_only:false; response_revision:1557; number_of_response:1; }","duration":"260.366438ms","start":"2026-05-13T22:06:56.200575Z","end":"2026-05-13T22:06:56.460942Z","steps":["trace[386751127] 'process raft request' (duration: 260.209744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:06:56.460988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.147567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T22:06:56.461256Z","caller":"traceutil/trace.go:171","msg":"trace[350382555] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1557; }","duration":"294.291401ms","start":"2026-05-13T22:06:56.166796Z","end":"2026-05-13T22:06:56.461088Z","steps":["trace[350382555] 'agreement among raft nodes before linearized reading' (duration: 294.113747ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:06:56.501039Z","caller":"traceutil/trace.go:171","msg":"trace[1396116181] transaction","detail":"{read_only:false; response_revision:1558; number_of_response:1; }","duration":"103.237416ms","start":"2026-05-13T22:06:56.397771Z","end":"2026-05-13T22:06:56.501009Z","steps":["trace[1396116181] 'process raft request' (duration: 100.646831ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:09:07.244344Z","caller":"traceutil/trace.go:171","msg":"trace[1285283138] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"177.021229ms","start":"2026-05-13T22:09:07.067298Z","end":"2026-05-13T22:09:07.244319Z","steps":["trace[1285283138] 'process raft request' (duration: 176.861305ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:09:07.245012Z","caller":"traceutil/trace.go:171","msg":"trace[199866849] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"177.195012ms","start":"2026-05-13T22:09:07.067794Z","end":"2026-05-13T22:09:07.244989Z","steps":["trace[199866849] 'process raft request' (duration: 177.08946ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:09:07.245338Z","caller":"traceutil/trace.go:171","msg":"trace[1480952157] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"177.793398ms","start":"2026-05-13T22:09:07.067533Z","end":"2026-05-13T22:09:07.245327Z","steps":["trace[1480952157] 'process raft request' (duration: 177.219583ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:09:07.245396Z","caller":"traceutil/trace.go:171","msg":"trace[513201329] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"176.28872ms","start":"2026-05-13T22:09:07.069093Z","end":"2026-05-13T22:09:07.245382Z","steps":["trace[513201329] 'process raft request' (duration: 175.851859ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T22:09:13.034457Z","caller":"traceutil/trace.go:171","msg":"trace[1867753995] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"314.17672ms","start":"2026-05-13T22:09:12.720258Z","end":"2026-05-13T22:09:13.034435Z","steps":["trace[1867753995] 'process raft request' (duration: 305.770807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T22:09:13.0346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T22:09:12.720239Z","time spent":"314.291962ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}