{"level":"warn","ts":"2026-07-28T09:20:22.257259Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-28T09:20:22.257346Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.115: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.115:2380","--initial-cluster=instance=https://199.204.45.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.115: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-07-28T09:20:22.257417Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-28T09:20:22.257429Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-07-28T09:20:22.257446Z","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-07-28T09:20:22.258115Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"]} {"level":"info","ts":"2026-07-28T09:20:22.258203Z","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.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.115: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-07-28T09:20:22.262855Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.451084ms"} {"level":"info","ts":"2026-07-28T09:20:22.27255Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3aaa5c123e150e0","cluster-id":"61ec519698823541"} {"level":"info","ts":"2026-07-28T09:20:22.272649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=()"} {"level":"info","ts":"2026-07-28T09:20:22.272681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 0"} {"level":"info","ts":"2026-07-28T09:20:22.272695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3aaa5c123e150e0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-28T09:20:22.272712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 1"} {"level":"info","ts":"2026-07-28T09:20:22.27276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"warn","ts":"2026-07-28T09:20:22.281381Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-28T09:20:22.283014Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-28T09:20:22.284863Z","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-07-28T09:20:22.291485Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3aaa5c123e150e0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-28T09:20:22.292096Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3aaa5c123e150e0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-28T09:20:22.292241Z","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-07-28T09:20:22.292275Z","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-07-28T09:20:22.292284Z","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-07-28T09:20:22.293402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"info","ts":"2026-07-28T09:20:22.29359Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","added-peer-id":"e3aaa5c123e150e0","added-peer-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-07-28T09:20:22.298386Z","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-07-28T09:20:22.298493Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-07-28T09:20:22.298639Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-07-28T09:20:22.299243Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3aaa5c123e150e0","initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-28T09:20:22.299279Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-28T09:20:23.074012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 is starting a new election at term 1"} {"level":"info","ts":"2026-07-28T09:20:23.074075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-28T09:20:23.074111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgPreVoteResp from e3aaa5c123e150e0 at term 1"} {"level":"info","ts":"2026-07-28T09:20:23.07413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became candidate at term 2"} {"level":"info","ts":"2026-07-28T09:20:23.074141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgVoteResp from e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-07-28T09:20:23.074156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became leader at term 2"} {"level":"info","ts":"2026-07-28T09:20:23.074193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3aaa5c123e150e0 elected leader e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-07-28T09:20:23.075209Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3aaa5c123e150e0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.115:2379]}","request-path":"/0/members/e3aaa5c123e150e0/attributes","cluster-id":"61ec519698823541","publish-timeout":"7s"} {"level":"info","ts":"2026-07-28T09:20:23.075255Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-28T09:20:23.075705Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-28T09:20:23.075828Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-28T09:20:23.079334Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-28T09:20:23.079334Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.115:2379"} {"level":"info","ts":"2026-07-28T09:20:23.08023Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","cluster-version":"3.5"} {"level":"info","ts":"2026-07-28T09:20:23.080411Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-28T09:20:23.08046Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-28T09:20:23.080667Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-28T09:20:23.080736Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-28T09:20:48.155809Z","caller":"traceutil/trace.go:171","msg":"trace[330249366] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"721.455579ms","start":"2026-07-28T09:20:47.434327Z","end":"2026-07-28T09:20:48.155783Z","steps":["trace[330249366] 'process raft request' (duration: 721.309694ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:20:48.155767Z","caller":"traceutil/trace.go:171","msg":"trace[222066743] linearizableReadLoop","detail":"{readStateIndex:438; appliedIndex:437; }","duration":"191.36559ms","start":"2026-07-28T09:20:47.964381Z","end":"2026-07-28T09:20:48.155747Z","steps":["trace[222066743] 'read index received' (duration: 191.173553ms)","trace[222066743] 'applied index is now lower than readState.Index' (duration: 191.306µs)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:20:48.155994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.516694ms","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-07-28T09:20:48.156047Z","caller":"traceutil/trace.go:171","msg":"trace[818128619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:421; }","duration":"191.70874ms","start":"2026-07-28T09:20:47.964326Z","end":"2026-07-28T09:20:48.156035Z","steps":["trace[818128619] 'agreement among raft nodes before linearized reading' (duration: 191.527204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:20:48.156281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:20:47.434313Z","time spent":"721.560832ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:20:48.828464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.402284ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T09:20:48.828547Z","caller":"traceutil/trace.go:171","msg":"trace[951510196] linearizableReadLoop","detail":"{readStateIndex:439; appliedIndex:438; }","duration":"432.888916ms","start":"2026-07-28T09:20:48.395645Z","end":"2026-07-28T09:20:48.828534Z","steps":["trace[951510196] 'read index received' (duration: 227.422889ms)","trace[951510196] 'applied index is now lower than readState.Index' (duration: 205.465197ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:20:48.8286Z","caller":"traceutil/trace.go:171","msg":"trace[1198677641] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"669.356317ms","start":"2026-07-28T09:20:48.159233Z","end":"2026-07-28T09:20:48.828589Z","steps":["trace[1198677641] 'process raft request' (duration: 463.633123ms)","trace[1198677641] 'compare' (duration: 205.304441ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:20:48.828642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:20:48.159209Z","time spent":"669.41433ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:20:48.828771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.110322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T09:20:48.828885Z","caller":"traceutil/trace.go:171","msg":"trace[1437664751] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:422; }","duration":"433.288678ms","start":"2026-07-28T09:20:48.395574Z","end":"2026-07-28T09:20:48.828863Z","steps":["trace[1437664751] 'agreement among raft nodes before linearized reading' (duration: 433.125173ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:20:48.828928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:20:48.395555Z","time spent":"433.364161ms","remote":"127.0.0.1:53360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-28T09:20:48.832011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.019064ms","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-07-28T09:20:48.83205Z","caller":"traceutil/trace.go:171","msg":"trace[1538793416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:423; }","duration":"165.086956ms","start":"2026-07-28T09:20:48.666953Z","end":"2026-07-28T09:20:48.83204Z","steps":["trace[1538793416] 'agreement among raft nodes before linearized reading' (duration: 165.025264ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:20:48.832058Z","caller":"traceutil/trace.go:171","msg":"trace[942489641] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"304.313224ms","start":"2026-07-28T09:20:48.52771Z","end":"2026-07-28T09:20:48.832024Z","steps":["trace[942489641] 'process raft request' (duration: 304.156559ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:20:48.832166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:20:48.527685Z","time spent":"304.419847ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T09:21:00.70682Z","caller":"traceutil/trace.go:171","msg":"trace[479494848] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"561.421366ms","start":"2026-07-28T09:21:00.14537Z","end":"2026-07-28T09:21:00.706792Z","steps":["trace[479494848] 'process raft request' (duration: 561.274981ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:21:00.706985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:00.14535Z","time spent":"561.56285ms","remote":"127.0.0.1:53418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-28T09:21:00.712663Z","caller":"traceutil/trace.go:171","msg":"trace[36662843] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"564.32069ms","start":"2026-07-28T09:21:00.148314Z","end":"2026-07-28T09:21:00.712635Z","steps":["trace[36662843] 'process raft request' (duration: 564.167795ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:21:00.712838Z","caller":"traceutil/trace.go:171","msg":"trace[1894919191] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"554.547883ms","start":"2026-07-28T09:21:00.158254Z","end":"2026-07-28T09:21:00.712802Z","steps":["trace[1894919191] 'process raft request' (duration: 554.366988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:21:00.712955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:00.158192Z","time spent":"554.715979ms","remote":"127.0.0.1:53418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-28T09:21:00.712924Z","caller":"traceutil/trace.go:171","msg":"trace[1634092766] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"552.498446ms","start":"2026-07-28T09:21:00.160393Z","end":"2026-07-28T09:21:00.712891Z","steps":["trace[1634092766] 'process raft request' (duration: 552.326631ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:21:00.713073Z","caller":"traceutil/trace.go:171","msg":"trace[1928846984] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"552.083513ms","start":"2026-07-28T09:21:00.160979Z","end":"2026-07-28T09:21:00.713062Z","steps":["trace[1928846984] 'process raft request' (duration: 551.904767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:21:00.713135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:00.16096Z","time spent":"552.142795ms","remote":"127.0.0.1:53418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-28T09:21:00.713094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:00.14828Z","time spent":"564.574328ms","remote":"127.0.0.1:53418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-28T09:21:00.713234Z","caller":"traceutil/trace.go:171","msg":"trace[1430367677] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"525.17958ms","start":"2026-07-28T09:21:00.188047Z","end":"2026-07-28T09:21:00.713227Z","steps":["trace[1430367677] 'process raft request' (duration: 524.989404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:21:00.713105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:00.160375Z","time spent":"552.645251ms","remote":"127.0.0.1:53418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-28T09:21:00.713286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:00.187991Z","time spent":"525.264132ms","remote":"127.0.0.1:53418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-28T09:21:00.713193Z","caller":"traceutil/trace.go:171","msg":"trace[1166162075] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"552.672032ms","start":"2026-07-28T09:21:00.160491Z","end":"2026-07-28T09:21:00.713163Z","steps":["trace[1166162075] 'process raft request' (duration: 552.28389ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:21:00.713374Z","caller":"traceutil/trace.go:171","msg":"trace[789763476] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"536.26837ms","start":"2026-07-28T09:21:00.177099Z","end":"2026-07-28T09:21:00.713367Z","steps":["trace[789763476] 'process raft request' (duration: 535.832046ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:21:00.713393Z","caller":"traceutil/trace.go:171","msg":"trace[515959482] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"499.013451ms","start":"2026-07-28T09:21:00.21437Z","end":"2026-07-28T09:21:00.713383Z","steps":["trace[515959482] 'process raft request' (duration: 498.711051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:21:00.713427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:00.177082Z","time spent":"536.309751ms","remote":"127.0.0.1:53418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-28T09:21:00.713453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:00.214348Z","time spent":"499.078103ms","remote":"127.0.0.1:53418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-28T09:21:00.713485Z","caller":"traceutil/trace.go:171","msg":"trace[1635659364] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"532.619091ms","start":"2026-07-28T09:21:00.180859Z","end":"2026-07-28T09:21:00.713478Z","steps":["trace[1635659364] 'process raft request' (duration: 532.102005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:21:00.713514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:00.180844Z","time spent":"532.657373ms","remote":"127.0.0.1:53418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-28T09:21:00.71352Z","caller":"traceutil/trace.go:171","msg":"trace[1909315163] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"401.428984ms","start":"2026-07-28T09:21:00.312085Z","end":"2026-07-28T09:21:00.713514Z","steps":["trace[1909315163] 'process raft request' (duration: 401.092464ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:21:00.713496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:00.160479Z","time spent":"552.92828ms","remote":"127.0.0.1:53418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-28T09:21:00.713567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:00.312056Z","time spent":"401.483216ms","remote":"127.0.0.1:53418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-28T09:21:00.713355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.805789ms","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-07-28T09:21:00.71362Z","caller":"traceutil/trace.go:171","msg":"trace[177556259] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:494; }","duration":"505.16437ms","start":"2026-07-28T09:21:00.208441Z","end":"2026-07-28T09:21:00.713605Z","steps":["trace[177556259] 'agreement among raft nodes before linearized reading' (duration: 504.825199ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:21:00.713236Z","caller":"traceutil/trace.go:171","msg":"trace[1021586783] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:501; }","duration":"504.672964ms","start":"2026-07-28T09:21:00.208553Z","end":"2026-07-28T09:21:00.713226Z","steps":["trace[1021586783] 'read index received' (duration: 498.150983ms)","trace[1021586783] 'applied index is now lower than readState.Index' (duration: 6.521451ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:21:00.713676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:00.208405Z","time spent":"505.257343ms","remote":"127.0.0.1:53662","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-07-28T09:21:00.715132Z","caller":"traceutil/trace.go:171","msg":"trace[477316826] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"563.168132ms","start":"2026-07-28T09:21:00.151944Z","end":"2026-07-28T09:21:00.715112Z","steps":["trace[477316826] 'process raft request' (duration: 560.61762ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:21:00.715276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:00.151918Z","time spent":"563.317997ms","remote":"127.0.0.1:53418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-28T09:21:00.724018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.271266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T09:21:00.724084Z","caller":"traceutil/trace.go:171","msg":"trace[434172246] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"514.376389ms","start":"2026-07-28T09:21:00.209694Z","end":"2026-07-28T09:21:00.72407Z","steps":["trace[434172246] 'agreement among raft nodes before linearized reading' (duration: 514.262216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:21:00.724113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:00.209679Z","time spent":"514.428231ms","remote":"127.0.0.1:53360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-28T09:21:00.724394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.44048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T09:21:00.724411Z","caller":"traceutil/trace.go:171","msg":"trace[685993885] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"329.487941ms","start":"2026-07-28T09:21:00.394918Z","end":"2026-07-28T09:21:00.724406Z","steps":["trace[685993885] 'agreement among raft nodes before linearized reading' (duration: 329.46114ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:21:00.724427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:00.394898Z","time spent":"329.523552ms","remote":"127.0.0.1:53348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-28T09:21:04.885943Z","caller":"traceutil/trace.go:171","msg":"trace[1746925583] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"141.645115ms","start":"2026-07-28T09:21:04.744267Z","end":"2026-07-28T09:21:04.885912Z","steps":["trace[1746925583] 'process raft request' (duration: 141.408468ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:21:07.769382Z","caller":"traceutil/trace.go:171","msg":"trace[714108221] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"410.665005ms","start":"2026-07-28T09:21:07.358696Z","end":"2026-07-28T09:21:07.769361Z","steps":["trace[714108221] 'process raft request' (duration: 410.156938ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:21:07.769511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:07.358674Z","time spent":"410.770027ms","remote":"127.0.0.1:53464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036157,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:21:07.769558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.234722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T09:21:07.769667Z","caller":"traceutil/trace.go:171","msg":"trace[1156897126] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"374.404268ms","start":"2026-07-28T09:21:07.395232Z","end":"2026-07-28T09:21:07.769636Z","steps":["trace[1156897126] 'agreement among raft nodes before linearized reading' (duration: 374.206281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:21:07.769717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:07.395219Z","time spent":"374.49027ms","remote":"127.0.0.1:53360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-28T09:21:07.769867Z","caller":"traceutil/trace.go:171","msg":"trace[603310104] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"374.068057ms","start":"2026-07-28T09:21:07.395258Z","end":"2026-07-28T09:21:07.769326Z","steps":["trace[603310104] 'read index received' (duration: 373.402755ms)","trace[603310104] 'applied index is now lower than readState.Index' (duration: 664.292µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:21:07.964776Z","caller":"traceutil/trace.go:171","msg":"trace[2123414933] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"131.108704ms","start":"2026-07-28T09:21:07.833638Z","end":"2026-07-28T09:21:07.964746Z","steps":["trace[2123414933] 'process raft request' (duration: 110.904009ms)","trace[2123414933] 'compare' (duration: 19.624336ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:21:08.619336Z","caller":"traceutil/trace.go:171","msg":"trace[2024000710] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"638.803207ms","start":"2026-07-28T09:21:07.980514Z","end":"2026-07-28T09:21:08.619318Z","steps":["trace[2024000710] 'process raft request' (duration: 638.668652ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:21:08.619464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:07.980486Z","time spent":"638.90595ms","remote":"127.0.0.1:53574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:21:10.341132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.65917ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T09:21:10.341266Z","caller":"traceutil/trace.go:171","msg":"trace[906738691] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:587; }","duration":"164.800464ms","start":"2026-07-28T09:21:10.176442Z","end":"2026-07-28T09:21:10.341242Z","steps":["trace[906738691] 'range keys from in-memory index tree' (duration: 164.640859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:21:10.341792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.649441162s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T09:21:10.341916Z","caller":"traceutil/trace.go:171","msg":"trace[447261337] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"1.429050163s","start":"2026-07-28T09:21:08.912844Z","end":"2026-07-28T09:21:10.341895Z","steps":["trace[447261337] 'read index received' (duration: 38.712µs)","trace[447261337] 'applied index is now lower than readState.Index' (duration: 1.42900738s)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:21:10.342013Z","caller":"traceutil/trace.go:171","msg":"trace[1785439884] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"1.965173976s","start":"2026-07-28T09:21:08.376824Z","end":"2026-07-28T09:21:10.341998Z","steps":["trace[1785439884] 'process raft request' (duration: 315.437744ms)","trace[1785439884] 'compare' (duration: 1.649025569s)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:21:10.34212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:08.376798Z","time spent":"1.9652771s","remote":"127.0.0.1:53464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036197,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:21:10.34646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.4336095s","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-07-28T09:21:10.346611Z","caller":"traceutil/trace.go:171","msg":"trace[123076824] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:588; }","duration":"1.433746215s","start":"2026-07-28T09:21:08.912784Z","end":"2026-07-28T09:21:10.34653Z","steps":["trace[123076824] 'agreement among raft nodes before linearized reading' (duration: 1.429562259s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:21:10.346699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:08.912759Z","time spent":"1.43392625s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-28T09:21:10.385987Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.405416568s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-28T09:21:10.386836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.592693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-28T09:21:10.386914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.442655056s","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-07-28T09:21:10.387062Z","caller":"traceutil/trace.go:171","msg":"trace[1973308453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"992.795119ms","start":"2026-07-28T09:21:09.394172Z","end":"2026-07-28T09:21:10.386967Z","steps":["trace[1973308453] 'agreement among raft nodes before linearized reading' (duration: 992.586752ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:21:10.386874Z","caller":"traceutil/trace.go:171","msg":"trace[2105817551] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"1.40643755s","start":"2026-07-28T09:21:08.980416Z","end":"2026-07-28T09:21:10.386854Z","steps":["trace[2105817551] 'process raft request' (duration: 1.405944024s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:21:10.38722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.395051151s","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-07-28T09:21:10.387083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.392774348s","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-07-28T09:21:10.387318Z","caller":"traceutil/trace.go:171","msg":"trace[1140933167] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:589; }","duration":"1.395117524s","start":"2026-07-28T09:21:08.992145Z","end":"2026-07-28T09:21:10.387263Z","steps":["trace[1140933167] 'agreement among raft nodes before linearized reading' (duration: 1.395023091s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:21:10.387357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:08.980388Z","time spent":"1.406826943s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:21:10.387369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:08.992131Z","time spent":"1.395206556s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-28T09:21:10.38717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:09.394152Z","time spent":"993.003866ms","remote":"127.0.0.1:53360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-28T09:21:10.387422Z","caller":"traceutil/trace.go:171","msg":"trace[1541864187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:589; }","duration":"1.393079227s","start":"2026-07-28T09:21:08.994267Z","end":"2026-07-28T09:21:10.387346Z","steps":["trace[1541864187] 'agreement among raft nodes before linearized reading' (duration: 1.392639623s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:21:10.387619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:08.99425Z","time spent":"1.393300604s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-28T09:21:10.387642Z","caller":"traceutil/trace.go:171","msg":"trace[1297067030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:589; }","duration":"1.442825971s","start":"2026-07-28T09:21:08.944177Z","end":"2026-07-28T09:21:10.387003Z","steps":["trace[1297067030] 'agreement among raft nodes before linearized reading' (duration: 1.442571173s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:21:10.387707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:08.944152Z","time spent":"1.443544705s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-28T09:21:10.889442Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833461828814178,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-28T09:21:11.09461Z","caller":"traceutil/trace.go:171","msg":"trace[1327845797] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"741.571375ms","start":"2026-07-28T09:21:10.353012Z","end":"2026-07-28T09:21:11.094583Z","steps":["trace[1327845797] 'process raft request' (duration: 655.669824ms)","trace[1327845797] 'compare' (duration: 85.653283ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:21:11.094747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:10.352995Z","time spent":"741.673169ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T09:21:11.094859Z","caller":"traceutil/trace.go:171","msg":"trace[618349129] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"703.004162ms","start":"2026-07-28T09:21:10.391823Z","end":"2026-07-28T09:21:11.094827Z","steps":["trace[618349129] 'process raft request' (duration: 702.711673ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:21:11.095117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:10.39179Z","time spent":"703.213579ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T09:21:11.095274Z","caller":"traceutil/trace.go:171","msg":"trace[1745766454] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"703.119026ms","start":"2026-07-28T09:21:10.392118Z","end":"2026-07-28T09:21:11.095237Z","steps":["trace[1745766454] 'process raft request' (duration: 702.483945ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:21:11.095445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:10.392105Z","time spent":"703.256211ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T09:21:11.096074Z","caller":"traceutil/trace.go:171","msg":"trace[471868612] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"695.131726ms","start":"2026-07-28T09:21:10.400923Z","end":"2026-07-28T09:21:11.096055Z","steps":["trace[471868612] 'process raft request' (duration: 694.980781ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:21:11.096066Z","caller":"traceutil/trace.go:171","msg":"trace[1460997097] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:617; }","duration":"707.23713ms","start":"2026-07-28T09:21:10.388804Z","end":"2026-07-28T09:21:11.096041Z","steps":["trace[1460997097] 'read index received' (duration: 619.901452ms)","trace[1460997097] 'applied index is now lower than readState.Index' (duration: 87.333988ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:21:11.096106Z","caller":"traceutil/trace.go:171","msg":"trace[1122657978] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"703.723236ms","start":"2026-07-28T09:21:10.392251Z","end":"2026-07-28T09:21:11.095974Z","steps":["trace[1122657978] 'process raft request' (duration: 702.779255ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:21:11.096147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:10.400896Z","time spent":"695.220749ms","remote":"127.0.0.1:53718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-28T09:21:11.096186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.384855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-28T09:21:11.096209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:10.392228Z","time spent":"703.926052ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T09:21:11.096226Z","caller":"traceutil/trace.go:171","msg":"trace[2025651947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"707.464997ms","start":"2026-07-28T09:21:10.388751Z","end":"2026-07-28T09:21:11.096216Z","steps":["trace[2025651947] 'agreement among raft nodes before linearized reading' (duration: 707.372174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:21:11.096603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:10.38872Z","time spent":"707.684165ms","remote":"127.0.0.1:53348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-28T09:21:12.603684Z","caller":"traceutil/trace.go:171","msg":"trace[186096712] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"209.006031ms","start":"2026-07-28T09:21:12.394651Z","end":"2026-07-28T09:21:12.603657Z","steps":["trace[186096712] 'read index received' (duration: 208.067711ms)","trace[186096712] 'applied index is now lower than readState.Index' (duration: 937.33µs)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:21:12.603868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.198367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T09:21:12.603926Z","caller":"traceutil/trace.go:171","msg":"trace[1594253750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"209.297871ms","start":"2026-07-28T09:21:12.39461Z","end":"2026-07-28T09:21:12.603908Z","steps":["trace[1594253750] 'agreement among raft nodes before linearized reading' (duration: 209.147866ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:21:12.604025Z","caller":"traceutil/trace.go:171","msg":"trace[902836016] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"534.967291ms","start":"2026-07-28T09:21:12.069032Z","end":"2026-07-28T09:21:12.604Z","steps":["trace[902836016] 'process raft request' (duration: 533.99476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:21:12.604174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:12.069012Z","time spent":"535.081406ms","remote":"127.0.0.1:53464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:21:27.306575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.008346243s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T09:21:27.306679Z","caller":"traceutil/trace.go:171","msg":"trace[660099878] linearizableReadLoop","detail":"{readStateIndex:792; appliedIndex:791; }","duration":"911.602189ms","start":"2026-07-28T09:21:26.395057Z","end":"2026-07-28T09:21:27.306659Z","steps":["trace[660099878] 'read index received' (duration: 28.081µs)","trace[660099878] 'applied index is now lower than readState.Index' (duration: 911.570928ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:21:27.306778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.718913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T09:21:27.306754Z","caller":"traceutil/trace.go:171","msg":"trace[1569417422] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"1.30739519s","start":"2026-07-28T09:21:25.999336Z","end":"2026-07-28T09:21:27.306732Z","steps":["trace[1569417422] 'process raft request' (duration: 298.816749ms)","trace[1569417422] 'compare' (duration: 1.008193588s)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:21:27.306809Z","caller":"traceutil/trace.go:171","msg":"trace[478622639] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:759; }","duration":"911.776995ms","start":"2026-07-28T09:21:26.395025Z","end":"2026-07-28T09:21:27.306802Z","steps":["trace[478622639] 'agreement among raft nodes before linearized reading' (duration: 911.712953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:21:27.306834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:26.39501Z","time spent":"911.816317ms","remote":"127.0.0.1:53360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-28T09:21:27.306881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:25.999317Z","time spent":"1.307505974s","remote":"127.0.0.1:53574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:21:27.30707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.240753ms","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-07-28T09:21:27.307112Z","caller":"traceutil/trace.go:171","msg":"trace[409424985] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:759; }","duration":"403.312105ms","start":"2026-07-28T09:21:26.903779Z","end":"2026-07-28T09:21:27.307091Z","steps":["trace[409424985] 'agreement among raft nodes before linearized reading' (duration: 403.204381ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:21:27.307133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:21:26.903757Z","time spent":"403.371417ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-28T09:21:27.307168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.251437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-28T09:21:27.307197Z","caller":"traceutil/trace.go:171","msg":"trace[2002197081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:759; }","duration":"144.33335ms","start":"2026-07-28T09:21:27.162856Z","end":"2026-07-28T09:21:27.307189Z","steps":["trace[2002197081] 'agreement among raft nodes before linearized reading' (duration: 144.227046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:21:27.307261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.319492ms","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-07-28T09:21:27.307297Z","caller":"traceutil/trace.go:171","msg":"trace[1905772052] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:759; }","duration":"145.353843ms","start":"2026-07-28T09:21:27.161937Z","end":"2026-07-28T09:21:27.30729Z","steps":["trace[1905772052] 'agreement among raft nodes before linearized reading' (duration: 145.281101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:21:27.307376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.096235ms","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-07-28T09:21:27.307402Z","caller":"traceutil/trace.go:171","msg":"trace[588332110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:759; }","duration":"141.156326ms","start":"2026-07-28T09:21:27.16624Z","end":"2026-07-28T09:21:27.307396Z","steps":["trace[588332110] 'agreement among raft nodes before linearized reading' (duration: 141.095354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:21:27.307413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.622335ms","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-07-28T09:21:27.307432Z","caller":"traceutil/trace.go:171","msg":"trace[23120347] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:759; }","duration":"146.668285ms","start":"2026-07-28T09:21:27.160758Z","end":"2026-07-28T09:21:27.307426Z","steps":["trace[23120347] 'agreement among raft nodes before linearized reading' (duration: 146.617254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:21:27.307552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.257942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T09:21:27.307606Z","caller":"traceutil/trace.go:171","msg":"trace[813689786] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:759; }","duration":"182.329805ms","start":"2026-07-28T09:21:27.125254Z","end":"2026-07-28T09:21:27.307584Z","steps":["trace[813689786] 'agreement among raft nodes before linearized reading' (duration: 182.280303ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:21:56.76526Z","caller":"traceutil/trace.go:171","msg":"trace[658286502] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"131.455212ms","start":"2026-07-28T09:21:56.633785Z","end":"2026-07-28T09:21:56.76524Z","steps":["trace[658286502] 'process raft request' (duration: 131.270826ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:22:03.178405Z","caller":"traceutil/trace.go:171","msg":"trace[503171142] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"386.051344ms","start":"2026-07-28T09:22:02.792333Z","end":"2026-07-28T09:22:03.178384Z","steps":["trace[503171142] 'process raft request' (duration: 385.92187ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:03.178556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:02.792305Z","time spent":"386.178418ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:22:03.763505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.981254ms","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-07-28T09:22:03.763577Z","caller":"traceutil/trace.go:171","msg":"trace[568119951] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:975; }","duration":"283.083888ms","start":"2026-07-28T09:22:03.480476Z","end":"2026-07-28T09:22:03.76356Z","steps":["trace[568119951] 'range keys from in-memory index tree' (duration: 282.83761ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:03.763627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.308308ms","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-07-28T09:22:03.763651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.954617ms","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-07-28T09:22:03.763679Z","caller":"traceutil/trace.go:171","msg":"trace[1093352669] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:975; }","duration":"260.37411ms","start":"2026-07-28T09:22:03.503278Z","end":"2026-07-28T09:22:03.763653Z","steps":["trace[1093352669] 'range keys from in-memory index tree' (duration: 260.192795ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:22:03.763721Z","caller":"traceutil/trace.go:171","msg":"trace[339782434] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:975; }","duration":"248.03459ms","start":"2026-07-28T09:22:03.51567Z","end":"2026-07-28T09:22:03.763704Z","steps":["trace[339782434] 'range keys from in-memory index tree' (duration: 247.765731ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:03.763852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.812293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T09:22:03.763885Z","caller":"traceutil/trace.go:171","msg":"trace[1021146963] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:975; }","duration":"368.875075ms","start":"2026-07-28T09:22:03.395001Z","end":"2026-07-28T09:22:03.763876Z","steps":["trace[1021146963] 'range keys from in-memory index tree' (duration: 368.719431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:03.763896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.069573ms","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-07-28T09:22:03.763913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:03.394985Z","time spent":"368.919627ms","remote":"127.0.0.1:53360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-28T09:22:03.763925Z","caller":"traceutil/trace.go:171","msg":"trace[30438935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:975; }","duration":"289.125015ms","start":"2026-07-28T09:22:03.474793Z","end":"2026-07-28T09:22:03.763918Z","steps":["trace[30438935] 'range keys from in-memory index tree' (duration: 288.98334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:03.763513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.658439ms","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-07-28T09:22:03.763957Z","caller":"traceutil/trace.go:171","msg":"trace[2024300160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:975; }","duration":"253.126875ms","start":"2026-07-28T09:22:03.510818Z","end":"2026-07-28T09:22:03.763944Z","steps":["trace[2024300160] 'range keys from in-memory index tree' (duration: 252.515785ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:22:04.109962Z","caller":"traceutil/trace.go:171","msg":"trace[571772467] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"342.307402ms","start":"2026-07-28T09:22:03.767632Z","end":"2026-07-28T09:22:04.109939Z","steps":["trace[571772467] 'process raft request' (duration: 342.156667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:04.110108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:03.767613Z","time spent":"342.421036ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T09:22:04.250822Z","caller":"traceutil/trace.go:171","msg":"trace[1396369425] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"482.430855ms","start":"2026-07-28T09:22:03.768372Z","end":"2026-07-28T09:22:04.250803Z","steps":["trace[1396369425] 'process raft request' (duration: 482.337942ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:22:04.251069Z","caller":"traceutil/trace.go:171","msg":"trace[2100147341] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1017; }","duration":"285.077733ms","start":"2026-07-28T09:22:03.965976Z","end":"2026-07-28T09:22:04.251054Z","steps":["trace[2100147341] 'read index received' (duration: 143.950837ms)","trace[2100147341] 'applied index is now lower than readState.Index' (duration: 141.126056ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:22:04.251102Z","caller":"traceutil/trace.go:171","msg":"trace[1376930335] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"481.136193ms","start":"2026-07-28T09:22:03.769954Z","end":"2026-07-28T09:22:04.25109Z","steps":["trace[1376930335] 'process raft request' (duration: 480.998799ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:22:04.25106Z","caller":"traceutil/trace.go:171","msg":"trace[1213296265] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"481.637119ms","start":"2026-07-28T09:22:03.769398Z","end":"2026-07-28T09:22:04.251035Z","steps":["trace[1213296265] 'process raft request' (duration: 481.367581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:04.251226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.234538ms","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-07-28T09:22:04.251259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.012841ms","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-07-28T09:22:04.251271Z","caller":"traceutil/trace.go:171","msg":"trace[1458140642] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:980; }","duration":"285.322981ms","start":"2026-07-28T09:22:03.965935Z","end":"2026-07-28T09:22:04.251258Z","steps":["trace[1458140642] 'agreement among raft nodes before linearized reading' (duration: 285.238548ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:04.251189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:03.769945Z","time spent":"481.188634ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T09:22:04.25129Z","caller":"traceutil/trace.go:171","msg":"trace[1845968825] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:980; }","duration":"129.074854ms","start":"2026-07-28T09:22:04.122206Z","end":"2026-07-28T09:22:04.251281Z","steps":["trace[1845968825] 'agreement among raft nodes before linearized reading' (duration: 129.017712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:04.251263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:03.769389Z","time spent":"481.828645ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T09:22:04.250861Z","caller":"traceutil/trace.go:171","msg":"trace[1395677821] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"482.978513ms","start":"2026-07-28T09:22:03.767854Z","end":"2026-07-28T09:22:04.250832Z","steps":["trace[1395677821] 'process raft request' (duration: 482.721214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:04.251781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:03.76784Z","time spent":"483.836131ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:22:04.251538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:03.768353Z","time spent":"482.527128ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T09:22:06.22385Z","caller":"traceutil/trace.go:171","msg":"trace[124625653] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"106.661266ms","start":"2026-07-28T09:22:06.117171Z","end":"2026-07-28T09:22:06.223832Z","steps":["trace[124625653] 'process raft request' (duration: 106.48425ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:22:14.448088Z","caller":"traceutil/trace.go:171","msg":"trace[1873930163] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1051; }","duration":"137.721905ms","start":"2026-07-28T09:22:14.310347Z","end":"2026-07-28T09:22:14.448069Z","steps":["trace[1873930163] 'read index received' (duration: 137.554859ms)","trace[1873930163] 'applied index is now lower than readState.Index' (duration: 166.306µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:22:14.448184Z","caller":"traceutil/trace.go:171","msg":"trace[1709748515] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"186.424457ms","start":"2026-07-28T09:22:14.261751Z","end":"2026-07-28T09:22:14.448176Z","steps":["trace[1709748515] 'process raft request' (duration: 186.19669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:14.448404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.869542ms","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-07-28T09:22:14.448426Z","caller":"traceutil/trace.go:171","msg":"trace[368032629] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1009; }","duration":"134.917404ms","start":"2026-07-28T09:22:14.313502Z","end":"2026-07-28T09:22:14.448419Z","steps":["trace[368032629] 'agreement among raft nodes before linearized reading' (duration: 134.78328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:14.448457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.090927ms","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-07-28T09:22:14.448531Z","caller":"traceutil/trace.go:171","msg":"trace[70356151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1009; }","duration":"138.210621ms","start":"2026-07-28T09:22:14.310303Z","end":"2026-07-28T09:22:14.448513Z","steps":["trace[70356151] 'agreement among raft nodes before linearized reading' (duration: 137.987724ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:14.448556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.084949ms","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-07-28T09:22:14.44861Z","caller":"traceutil/trace.go:171","msg":"trace[1551752897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1009; }","duration":"135.162002ms","start":"2026-07-28T09:22:14.313434Z","end":"2026-07-28T09:22:14.448596Z","steps":["trace[1551752897] 'agreement among raft nodes before linearized reading' (duration: 134.885443ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:14.44879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.830263ms","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-07-28T09:22:14.448817Z","caller":"traceutil/trace.go:171","msg":"trace[21078423] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1009; }","duration":"135.857575ms","start":"2026-07-28T09:22:14.312953Z","end":"2026-07-28T09:22:14.448811Z","steps":["trace[21078423] 'agreement among raft nodes before linearized reading' (duration: 135.296106ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:22:27.025783Z","caller":"traceutil/trace.go:171","msg":"trace[749906120] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"526.127245ms","start":"2026-07-28T09:22:26.499634Z","end":"2026-07-28T09:22:27.025762Z","steps":["trace[749906120] 'process raft request' (duration: 525.96998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:27.027062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:26.499619Z","time spent":"527.382646ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T09:22:27.027857Z","caller":"traceutil/trace.go:171","msg":"trace[525771438] linearizableReadLoop","detail":"{readStateIndex:1095; appliedIndex:1095; }","duration":"473.637016ms","start":"2026-07-28T09:22:26.554187Z","end":"2026-07-28T09:22:27.027824Z","steps":["trace[525771438] 'read index received' (duration: 473.628475ms)","trace[525771438] 'applied index is now lower than readState.Index' (duration: 7.2µs)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:22:27.028037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.830492ms","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-07-28T09:22:27.028085Z","caller":"traceutil/trace.go:171","msg":"trace[474159436] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1049; }","duration":"473.928575ms","start":"2026-07-28T09:22:26.554145Z","end":"2026-07-28T09:22:27.028074Z","steps":["trace[474159436] 'agreement among raft nodes before linearized reading' (duration: 473.761049ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:27.028118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:26.554126Z","time spent":"473.982687ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-28T09:22:27.528099Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833461828815175,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-28T09:22:27.830943Z","caller":"traceutil/trace.go:171","msg":"trace[1375418055] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"1.243423399s","start":"2026-07-28T09:22:26.587498Z","end":"2026-07-28T09:22:27.830922Z","steps":["trace[1375418055] 'process raft request' (duration: 1.243316326s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:27.831071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:26.587449Z","time spent":"1.243554234s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T09:22:27.862093Z","caller":"traceutil/trace.go:171","msg":"trace[1708673224] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"830.795589ms","start":"2026-07-28T09:22:27.031278Z","end":"2026-07-28T09:22:27.862074Z","steps":["trace[1708673224] 'process raft request' (duration: 830.638084ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:27.86235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:27.031262Z","time spent":"831.026507ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T09:22:27.86247Z","caller":"traceutil/trace.go:171","msg":"trace[2116666013] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"534.131524ms","start":"2026-07-28T09:22:27.328311Z","end":"2026-07-28T09:22:27.862442Z","steps":["trace[2116666013] 'process raft request' (duration: 533.71061ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:27.862542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.30489139s","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-07-28T09:22:27.862573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.304980253s","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-07-28T09:22:27.862591Z","caller":"traceutil/trace.go:171","msg":"trace[887809428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1052; }","duration":"1.304985704s","start":"2026-07-28T09:22:26.557597Z","end":"2026-07-28T09:22:27.862583Z","steps":["trace[887809428] 'agreement among raft nodes before linearized reading' (duration: 1.304803418s)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:22:27.862609Z","caller":"traceutil/trace.go:171","msg":"trace[81038976] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1052; }","duration":"1.305054915s","start":"2026-07-28T09:22:26.557545Z","end":"2026-07-28T09:22:27.8626Z","steps":["trace[81038976] 'agreement among raft nodes before linearized reading' (duration: 1.30489521s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:27.862635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:26.557534Z","time spent":"1.305095907s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-28T09:22:27.862616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:26.55758Z","time spent":"1.305030344s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-28T09:22:27.86264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:27.328288Z","time spent":"534.260318ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T09:22:27.862342Z","caller":"traceutil/trace.go:171","msg":"trace[1291268505] linearizableReadLoop","detail":"{readStateIndex:1098; appliedIndex:1095; }","duration":"834.349905ms","start":"2026-07-28T09:22:27.027976Z","end":"2026-07-28T09:22:27.862326Z","steps":["trace[1291268505] 'read index received' (duration: 802.886786ms)","trace[1291268505] 'applied index is now lower than readState.Index' (duration: 31.461689ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:22:27.86277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.118834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T09:22:27.862863Z","caller":"traceutil/trace.go:171","msg":"trace[1695279293] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1052; }","duration":"799.213897ms","start":"2026-07-28T09:22:27.06364Z","end":"2026-07-28T09:22:27.862854Z","steps":["trace[1695279293] 'agreement among raft nodes before linearized reading' (duration: 799.091773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:27.862791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.849202ms","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":"warn","ts":"2026-07-28T09:22:27.862949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.279706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T09:22:27.862976Z","caller":"traceutil/trace.go:171","msg":"trace[442722116] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1052; }","duration":"600.063989ms","start":"2026-07-28T09:22:27.262903Z","end":"2026-07-28T09:22:27.862967Z","steps":["trace[442722116] 'agreement among raft nodes before linearized reading' (duration: 599.836362ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:27.863037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:27.262887Z","time spent":"600.140481ms","remote":"127.0.0.1:52590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-28T09:22:27.862981Z","caller":"traceutil/trace.go:171","msg":"trace[1517465771] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1052; }","duration":"903.350219ms","start":"2026-07-28T09:22:26.959621Z","end":"2026-07-28T09:22:27.862971Z","steps":["trace[1517465771] 'agreement among raft nodes before linearized reading' (duration: 902.721579ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:27.863192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.30892055s","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-07-28T09:22:27.863202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:26.959583Z","time spent":"903.595167ms","remote":"127.0.0.1:52470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"info","ts":"2026-07-28T09:22:27.863223Z","caller":"traceutil/trace.go:171","msg":"trace[1803250928] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1052; }","duration":"1.308995723s","start":"2026-07-28T09:22:26.554218Z","end":"2026-07-28T09:22:27.863214Z","steps":["trace[1803250928] 'agreement among raft nodes before linearized reading' (duration: 1.308157126s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:27.863383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:26.554192Z","time spent":"1.309183359s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-28T09:22:27.862773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.783383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-28T09:22:27.862951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:27.06356Z","time spent":"799.360841ms","remote":"127.0.0.1:53544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-07-28T09:22:27.863572Z","caller":"traceutil/trace.go:171","msg":"trace[369360244] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1052; }","duration":"467.60163ms","start":"2026-07-28T09:22:27.395937Z","end":"2026-07-28T09:22:27.863539Z","steps":["trace[369360244] 'agreement among raft nodes before linearized reading' (duration: 466.793263ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:27.863684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:27.39592Z","time spent":"467.732544ms","remote":"127.0.0.1:53360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-28T09:22:28.034287Z","caller":"traceutil/trace.go:171","msg":"trace[1253510034] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"164.881388ms","start":"2026-07-28T09:22:27.869383Z","end":"2026-07-28T09:22:28.034264Z","steps":["trace[1253510034] 'process raft request' (duration: 164.810216ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:22:28.034346Z","caller":"traceutil/trace.go:171","msg":"trace[369338296] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"167.657318ms","start":"2026-07-28T09:22:27.866657Z","end":"2026-07-28T09:22:28.034315Z","steps":["trace[369338296] 'process raft request' (duration: 167.42082ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:22:29.567657Z","caller":"traceutil/trace.go:171","msg":"trace[1372841880] linearizableReadLoop","detail":"{readStateIndex:1102; appliedIndex:1101; }","duration":"351.949266ms","start":"2026-07-28T09:22:29.215687Z","end":"2026-07-28T09:22:29.567637Z","steps":["trace[1372841880] 'read index received' (duration: 351.76607ms)","trace[1372841880] 'applied index is now lower than readState.Index' (duration: 182.366µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:22:29.567757Z","caller":"traceutil/trace.go:171","msg":"trace[1212242278] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"532.930626ms","start":"2026-07-28T09:22:29.034798Z","end":"2026-07-28T09:22:29.567729Z","steps":["trace[1212242278] 'process raft request' (duration: 532.704748ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:29.567836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.124551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T09:22:29.567902Z","caller":"traceutil/trace.go:171","msg":"trace[1999659826] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1056; }","duration":"352.200314ms","start":"2026-07-28T09:22:29.215652Z","end":"2026-07-28T09:22:29.567853Z","steps":["trace[1999659826] 'agreement among raft nodes before linearized reading' (duration: 352.07875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:29.567926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:29.034779Z","time spent":"533.072311ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:22:29.567938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:29.215636Z","time spent":"352.293417ms","remote":"127.0.0.1:53442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":92,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-07-28T09:22:29.568158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.409601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T09:22:29.568331Z","caller":"traceutil/trace.go:171","msg":"trace[1978092645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1056; }","duration":"171.623098ms","start":"2026-07-28T09:22:29.396689Z","end":"2026-07-28T09:22:29.568312Z","steps":["trace[1978092645] 'agreement among raft nodes before linearized reading' (duration: 171.37956ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:30.49227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.26246ms","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-07-28T09:22:30.492238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.237429ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T09:22:30.492333Z","caller":"traceutil/trace.go:171","msg":"trace[1560571780] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1056; }","duration":"623.343243ms","start":"2026-07-28T09:22:29.868973Z","end":"2026-07-28T09:22:30.492316Z","steps":["trace[1560571780] 'range keys from in-memory index tree' (duration: 623.154897ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:22:30.492366Z","caller":"traceutil/trace.go:171","msg":"trace[1729788795] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1056; }","duration":"316.385254ms","start":"2026-07-28T09:22:30.175967Z","end":"2026-07-28T09:22:30.492353Z","steps":["trace[1729788795] 'range keys from in-memory index tree' (duration: 316.223739ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:30.492379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:29.86896Z","time spent":"623.409956ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-28T09:22:30.492419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.734944ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T09:22:30.492473Z","caller":"traceutil/trace.go:171","msg":"trace[287216151] linearizableReadLoop","detail":"{readStateIndex:1103; appliedIndex:1102; }","duration":"577.382025ms","start":"2026-07-28T09:22:29.91508Z","end":"2026-07-28T09:22:30.492462Z","steps":["trace[287216151] 'read index received' (duration: 269.472075ms)","trace[287216151] 'applied index is now lower than readState.Index' (duration: 307.90891ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:22:30.492595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.510849ms","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-07-28T09:22:30.492623Z","caller":"traceutil/trace.go:171","msg":"trace[1029288232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1057; }","duration":"577.566251ms","start":"2026-07-28T09:22:29.915048Z","end":"2026-07-28T09:22:30.492614Z","steps":["trace[1029288232] 'agreement among raft nodes before linearized reading' (duration: 577.481048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:30.492648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:29.915033Z","time spent":"577.608502ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-28T09:22:30.492629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.502477ms","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-07-28T09:22:30.492718Z","caller":"traceutil/trace.go:171","msg":"trace[560106074] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1057; }","duration":"454.62072ms","start":"2026-07-28T09:22:30.038087Z","end":"2026-07-28T09:22:30.492708Z","steps":["trace[560106074] 'agreement among raft nodes before linearized reading' (duration: 454.456054ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:30.49273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.913877ms","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-07-28T09:22:30.49275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:30.038072Z","time spent":"454.668572ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-28T09:22:30.492779Z","caller":"traceutil/trace.go:171","msg":"trace[2071833245] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1057; }","duration":"453.99761ms","start":"2026-07-28T09:22:30.03877Z","end":"2026-07-28T09:22:30.492768Z","steps":["trace[2071833245] 'agreement among raft nodes before linearized reading' (duration: 453.862665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:30.492807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:30.038754Z","time spent":"454.047252ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-28T09:22:30.493116Z","caller":"traceutil/trace.go:171","msg":"trace[1983450804] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"617.967899ms","start":"2026-07-28T09:22:29.87454Z","end":"2026-07-28T09:22:30.492508Z","steps":["trace[1983450804] 'process raft request' (duration: 310.08138ms)","trace[1983450804] 'compare' (duration: 307.523077ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:22:30.493186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:29.874524Z","time spent":"618.618321ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T09:22:30.673611Z","caller":"traceutil/trace.go:171","msg":"trace[541639533] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"176.35411ms","start":"2026-07-28T09:22:30.49723Z","end":"2026-07-28T09:22:30.673584Z","steps":["trace[541639533] 'process raft request' (duration: 176.119873ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:22:30.673904Z","caller":"traceutil/trace.go:171","msg":"trace[1122875115] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"176.450813ms","start":"2026-07-28T09:22:30.497429Z","end":"2026-07-28T09:22:30.67388Z","steps":["trace[1122875115] 'process raft request' (duration: 176.096822ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:22:30.673991Z","caller":"traceutil/trace.go:171","msg":"trace[1175854938] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"176.05344ms","start":"2026-07-28T09:22:30.497926Z","end":"2026-07-28T09:22:30.673979Z","steps":["trace[1175854938] 'process raft request' (duration: 175.776601ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:31.747902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.246319ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-28T09:22:31.748211Z","caller":"traceutil/trace.go:171","msg":"trace[45985988] linearizableReadLoop","detail":"{readStateIndex:1108; appliedIndex:1107; }","duration":"176.212785ms","start":"2026-07-28T09:22:31.571977Z","end":"2026-07-28T09:22:31.74819Z","steps":["trace[45985988] 'read index received' (duration: 52.701µs)","trace[45985988] 'applied index is now lower than readState.Index' (duration: 176.158694ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:22:31.74838Z","caller":"traceutil/trace.go:171","msg":"trace[364119379] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"118.574599ms","start":"2026-07-28T09:22:31.629791Z","end":"2026-07-28T09:22:31.748366Z","steps":["trace[364119379] 'process raft request' (duration: 118.253259ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:31.748479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.465124ms","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-07-28T09:22:31.748571Z","caller":"traceutil/trace.go:171","msg":"trace[1353183039] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1062; }","duration":"176.624039ms","start":"2026-07-28T09:22:31.571931Z","end":"2026-07-28T09:22:31.748555Z","steps":["trace[1353183039] 'agreement among raft nodes before linearized reading' (duration: 176.33238ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:22:31.938768Z","caller":"traceutil/trace.go:171","msg":"trace[378251233] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"186.742957ms","start":"2026-07-28T09:22:31.752Z","end":"2026-07-28T09:22:31.938743Z","steps":["trace[378251233] 'process raft request' (duration: 125.38315ms)","trace[378251233] 'compare' (duration: 61.206322ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:22:32.813845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.421105ms","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-07-28T09:22:32.813943Z","caller":"traceutil/trace.go:171","msg":"trace[992022424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1065; }","duration":"135.539509ms","start":"2026-07-28T09:22:32.678378Z","end":"2026-07-28T09:22:32.813918Z","steps":["trace[992022424] 'range keys from in-memory index tree' (duration: 135.25793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:32.814124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.877409ms","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-07-28T09:22:32.814361Z","caller":"traceutil/trace.go:171","msg":"trace[1008800903] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1065; }","duration":"136.16882ms","start":"2026-07-28T09:22:32.678147Z","end":"2026-07-28T09:22:32.814316Z","steps":["trace[1008800903] 'range keys from in-memory index tree' (duration: 135.365253ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:32.814052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.813777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-28T09:22:32.814676Z","caller":"traceutil/trace.go:171","msg":"trace[1805661560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1065; }","duration":"136.452149ms","start":"2026-07-28T09:22:32.678148Z","end":"2026-07-28T09:22:32.8146Z","steps":["trace[1805661560] 'range keys from in-memory index tree' (duration: 135.425155ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:49.292455Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833461828815441,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-28T09:22:49.801346Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833461828815441,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-28T09:22:50.301557Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833461828815441,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-28T09:22:50.801966Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833461828815441,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-28T09:22:51.302801Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833461828815441,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-28T09:22:51.398076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999941067s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-28T09:22:51.398162Z","caller":"traceutil/trace.go:171","msg":"trace[154541232] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000069691s","start":"2026-07-28T09:22:49.398071Z","end":"2026-07-28T09:22:51.398141Z","steps":["trace[154541232] 'agreement among raft nodes before linearized reading' (duration: 1.999963098s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:51.398202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:49.398055Z","time spent":"2.000137563s","remote":"127.0.0.1:53360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-28T09:22:51.531133Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.850387222s","expected-duration":"1s"} {"level":"info","ts":"2026-07-28T09:22:51.53156Z","caller":"traceutil/trace.go:171","msg":"trace[408459089] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"2.850863387s","start":"2026-07-28T09:22:48.680667Z","end":"2026-07-28T09:22:51.53153Z","steps":["trace[408459089] 'process raft request' (duration: 2.850688171s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:51.53189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:48.680648Z","time spent":"2.851028873s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T09:22:51.536195Z","caller":"traceutil/trace.go:171","msg":"trace[879529026] linearizableReadLoop","detail":"{readStateIndex:1221; appliedIndex:1220; }","duration":"2.744267047s","start":"2026-07-28T09:22:48.791906Z","end":"2026-07-28T09:22:51.536174Z","steps":["trace[879529026] 'read index received' (duration: 2.739825383s)","trace[879529026] 'applied index is now lower than readState.Index' (duration: 4.440464ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:22:51.536521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.744612577s","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-07-28T09:22:51.536554Z","caller":"traceutil/trace.go:171","msg":"trace[1574843240] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1168; }","duration":"2.74467353s","start":"2026-07-28T09:22:48.791873Z","end":"2026-07-28T09:22:51.536547Z","steps":["trace[1574843240] 'agreement among raft nodes before linearized reading' (duration: 2.744539045s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:51.536576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:48.79186Z","time spent":"2.74471162s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-28T09:22:51.536719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.651546624s","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-07-28T09:22:51.536743Z","caller":"traceutil/trace.go:171","msg":"trace[1830985228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1168; }","duration":"2.651570345s","start":"2026-07-28T09:22:48.885167Z","end":"2026-07-28T09:22:51.536737Z","steps":["trace[1830985228] 'agreement among raft nodes before linearized reading' (duration: 2.651512593s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:51.536759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:48.885097Z","time spent":"2.651658537s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-28T09:22:51.536768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.928726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-28T09:22:51.536845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.638584ms","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-07-28T09:22:51.536849Z","caller":"traceutil/trace.go:171","msg":"trace[935740615] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1168; }","duration":"138.03796ms","start":"2026-07-28T09:22:51.398794Z","end":"2026-07-28T09:22:51.536831Z","steps":["trace[935740615] 'agreement among raft nodes before linearized reading' (duration: 137.917896ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:22:51.53687Z","caller":"traceutil/trace.go:171","msg":"trace[415983032] 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:1168; }","duration":"968.707096ms","start":"2026-07-28T09:22:50.568156Z","end":"2026-07-28T09:22:51.536863Z","steps":["trace[415983032] 'agreement among raft nodes before linearized reading' (duration: 968.671755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:51.536892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:50.56813Z","time spent":"968.756068ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-07-28T09:22:51.537018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.529898027s","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-07-28T09:22:51.537042Z","caller":"traceutil/trace.go:171","msg":"trace[1610029650] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1168; }","duration":"1.529957538s","start":"2026-07-28T09:22:50.007079Z","end":"2026-07-28T09:22:51.537036Z","steps":["trace[1610029650] 'agreement among raft nodes before linearized reading' (duration: 1.529899207s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:51.537058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:50.007061Z","time spent":"1.52999343s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-28T09:22:51.537166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.552032562s","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-07-28T09:22:51.537195Z","caller":"traceutil/trace.go:171","msg":"trace[1437159927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1168; }","duration":"2.552090004s","start":"2026-07-28T09:22:48.985098Z","end":"2026-07-28T09:22:51.537188Z","steps":["trace[1437159927] 'agreement among raft nodes before linearized reading' (duration: 2.552028902s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:51.537211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:48.985081Z","time spent":"2.552126776s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-28T09:22:51.537395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.566087018s","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-07-28T09:22:51.537431Z","caller":"traceutil/trace.go:171","msg":"trace[1001771881] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1168; }","duration":"2.56614995s","start":"2026-07-28T09:22:48.971272Z","end":"2026-07-28T09:22:51.537422Z","steps":["trace[1001771881] 'agreement among raft nodes before linearized reading' (duration: 2.566073327s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:22:51.537458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:22:48.971257Z","time spent":"2.566193401s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-28T09:23:09.944273Z","caller":"traceutil/trace.go:171","msg":"trace[1855626982] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"117.967939ms","start":"2026-07-28T09:23:09.826283Z","end":"2026-07-28T09:23:09.94425Z","steps":["trace[1855626982] 'process raft request' (duration: 117.852876ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:09.944464Z","caller":"traceutil/trace.go:171","msg":"trace[1583530242] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1304; }","duration":"117.34868ms","start":"2026-07-28T09:23:09.827076Z","end":"2026-07-28T09:23:09.944425Z","steps":["trace[1583530242] 'read index received' (duration: 117.318959ms)","trace[1583530242] 'applied index is now lower than readState.Index' (duration: 26.701µs)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:23:09.944755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.648389ms","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-07-28T09:23:09.944807Z","caller":"traceutil/trace.go:171","msg":"trace[871465805] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1248; }","duration":"117.776523ms","start":"2026-07-28T09:23:09.827018Z","end":"2026-07-28T09:23:09.944795Z","steps":["trace[871465805] 'agreement among raft nodes before linearized reading' (duration: 117.560896ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:09.948988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.486441ms","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-07-28T09:23:09.949029Z","caller":"traceutil/trace.go:171","msg":"trace[1679119323] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"120.207773ms","start":"2026-07-28T09:23:09.828804Z","end":"2026-07-28T09:23:09.949012Z","steps":["trace[1679119323] 'process raft request' (duration: 119.897413ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:09.949079Z","caller":"traceutil/trace.go:171","msg":"trace[126724830] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1250; }","duration":"120.612025ms","start":"2026-07-28T09:23:09.82845Z","end":"2026-07-28T09:23:09.949062Z","steps":["trace[126724830] 'agreement among raft nodes before linearized reading' (duration: 120.400158ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:09.949066Z","caller":"traceutil/trace.go:171","msg":"trace[352900702] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"119.878872ms","start":"2026-07-28T09:23:09.829165Z","end":"2026-07-28T09:23:09.949044Z","steps":["trace[352900702] 'process raft request' (duration: 119.647244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:11.734269Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833461828815637,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-28T09:23:13.39647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00010151s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-28T09:23:13.396567Z","caller":"traceutil/trace.go:171","msg":"trace[1097859168] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000216234s","start":"2026-07-28T09:23:11.396335Z","end":"2026-07-28T09:23:13.396551Z","steps":["trace[1097859168] 'agreement among raft nodes before linearized reading' (duration: 2.00009872s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:13.396614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:11.39627Z","time spent":"2.000335358s","remote":"127.0.0.1:53360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-28T09:23:13.461741Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.694858627s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-28T09:23:13.863885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.097109622s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-28T09:23:13.864859Z","caller":"traceutil/trace.go:171","msg":"trace[214508474] linearizableReadLoop","detail":"{readStateIndex:1319; appliedIndex:1317; }","duration":"2.631555257s","start":"2026-07-28T09:23:11.233251Z","end":"2026-07-28T09:23:13.864806Z","steps":["trace[214508474] 'read index received' (duration: 533.562607ms)","trace[214508474] 'applied index is now lower than readState.Index' (duration: 2.09798953s)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:23:13.864973Z","caller":"traceutil/trace.go:171","msg":"trace[1138760153] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"2.253574578s","start":"2026-07-28T09:23:11.611385Z","end":"2026-07-28T09:23:13.864959Z","steps":["trace[1138760153] 'process raft request' (duration: 2.252608617s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:13.865071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:11.611356Z","time spent":"2.2536429s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:23:13.881202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.647925507s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T09:23:13.881277Z","caller":"traceutil/trace.go:171","msg":"trace[1500338233] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1262; }","duration":"2.64802191s","start":"2026-07-28T09:23:11.233238Z","end":"2026-07-28T09:23:13.88126Z","steps":["trace[1500338233] 'agreement among raft nodes before linearized reading' (duration: 2.632531288s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:13.881323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:11.233223Z","time spent":"2.648084712s","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-28T09:23:13.881433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.621580053s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T09:23:13.881533Z","caller":"traceutil/trace.go:171","msg":"trace[444152415] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1263; }","duration":"2.621707627s","start":"2026-07-28T09:23:11.259791Z","end":"2026-07-28T09:23:13.881498Z","steps":["trace[444152415] 'agreement among raft nodes before linearized reading' (duration: 2.621576903s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:13.881564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:11.259772Z","time spent":"2.62178441s","remote":"127.0.0.1:56464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-07-28T09:23:13.881721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.195334763s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T09:23:13.881773Z","caller":"traceutil/trace.go:171","msg":"trace[657896734] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1263; }","duration":"1.195430356s","start":"2026-07-28T09:23:12.68633Z","end":"2026-07-28T09:23:13.88176Z","steps":["trace[657896734] 'agreement among raft nodes before linearized reading' (duration: 1.195306502s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:13.881806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:12.686312Z","time spent":"1.195488078s","remote":"127.0.0.1:53592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":45,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-07-28T09:23:13.881817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.919761509s","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-07-28T09:23:13.88185Z","caller":"traceutil/trace.go:171","msg":"trace[1068143098] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1263; }","duration":"1.919811431s","start":"2026-07-28T09:23:11.96203Z","end":"2026-07-28T09:23:13.881841Z","steps":["trace[1068143098] 'agreement among raft nodes before linearized reading' (duration: 1.919698917s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:13.881851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.928070309s","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-07-28T09:23:13.881875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:11.962018Z","time spent":"1.919851232s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-28T09:23:13.88189Z","caller":"traceutil/trace.go:171","msg":"trace[1403339194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1263; }","duration":"1.928154211s","start":"2026-07-28T09:23:11.953725Z","end":"2026-07-28T09:23:13.88188Z","steps":["trace[1403339194] 'agreement among raft nodes before linearized reading' (duration: 1.928029847s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:13.881923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:11.953694Z","time spent":"1.928221443s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-28T09:23:13.882024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.933186274s","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-07-28T09:23:13.882044Z","caller":"traceutil/trace.go:171","msg":"trace[1731600963] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1263; }","duration":"1.933239636s","start":"2026-07-28T09:23:11.948798Z","end":"2026-07-28T09:23:13.882038Z","steps":["trace[1731600963] 'agreement among raft nodes before linearized reading' (duration: 1.933178454s)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:13.882051Z","caller":"traceutil/trace.go:171","msg":"trace[1737336129] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"1.087551493s","start":"2026-07-28T09:23:12.79449Z","end":"2026-07-28T09:23:13.882041Z","steps":["trace[1737336129] 'process raft request' (duration: 1.083683428s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:13.882061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:11.948782Z","time spent":"1.933275057s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-28T09:23:13.882057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.923917464s","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-07-28T09:23:13.88209Z","caller":"traceutil/trace.go:171","msg":"trace[1161748095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1263; }","duration":"1.923974325s","start":"2026-07-28T09:23:11.958106Z","end":"2026-07-28T09:23:13.882081Z","steps":["trace[1161748095] 'agreement among raft nodes before linearized reading' (duration: 1.923893553s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:13.882116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:11.958091Z","time spent":"1.924018747s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-28T09:23:13.882147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.000974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T09:23:13.882168Z","caller":"traceutil/trace.go:171","msg":"trace[1047491939] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1263; }","duration":"485.055916ms","start":"2026-07-28T09:23:13.397106Z","end":"2026-07-28T09:23:13.882162Z","steps":["trace[1047491939] 'agreement among raft nodes before linearized reading' (duration: 485.025445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:13.882188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:13.397092Z","time spent":"485.092627ms","remote":"127.0.0.1:53348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-28T09:23:13.882236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.925460454s","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-07-28T09:23:13.882267Z","caller":"traceutil/trace.go:171","msg":"trace[1496359175] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1263; }","duration":"1.925516286s","start":"2026-07-28T09:23:11.956742Z","end":"2026-07-28T09:23:13.882258Z","steps":["trace[1496359175] 'agreement among raft nodes before linearized reading' (duration: 1.925430993s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:13.882293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:11.956727Z","time spent":"1.925559707s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-28T09:23:13.882298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.498824ms","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-07-28T09:23:13.88232Z","caller":"traceutil/trace.go:171","msg":"trace[1839012371] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1263; }","duration":"596.564406ms","start":"2026-07-28T09:23:13.285748Z","end":"2026-07-28T09:23:13.882312Z","steps":["trace[1839012371] 'agreement among raft nodes before linearized reading' (duration: 596.529505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:13.882339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:13.285725Z","time spent":"596.609288ms","remote":"127.0.0.1:56452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-28T09:23:13.882117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:12.794434Z","time spent":"1.087640906s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:23:13.882428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.060082263s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T09:23:13.882447Z","caller":"traceutil/trace.go:171","msg":"trace[860517134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1263; }","duration":"1.060127785s","start":"2026-07-28T09:23:12.822314Z","end":"2026-07-28T09:23:13.882442Z","steps":["trace[860517134] 'agreement among raft nodes before linearized reading' (duration: 1.060100704s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:13.882463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:12.822299Z","time spent":"1.060160427s","remote":"127.0.0.1:53360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-28T09:23:14.019992Z","caller":"traceutil/trace.go:171","msg":"trace[1517462241] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"132.541121ms","start":"2026-07-28T09:23:13.887434Z","end":"2026-07-28T09:23:14.019975Z","steps":["trace[1517462241] 'process raft request' (duration: 132.466799ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:14.020024Z","caller":"traceutil/trace.go:171","msg":"trace[1721622648] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"132.590083ms","start":"2026-07-28T09:23:13.887366Z","end":"2026-07-28T09:23:14.019956Z","steps":["trace[1721622648] 'process raft request' (duration: 104.927317ms)","trace[1721622648] 'compare' (duration: 27.512301ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:23:14.020256Z","caller":"traceutil/trace.go:171","msg":"trace[1578671380] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"131.653312ms","start":"2026-07-28T09:23:13.888595Z","end":"2026-07-28T09:23:14.020248Z","steps":["trace[1578671380] 'process raft request' (duration: 131.360963ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:14.020377Z","caller":"traceutil/trace.go:171","msg":"trace[1180014782] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"131.565309ms","start":"2026-07-28T09:23:13.888806Z","end":"2026-07-28T09:23:14.020371Z","steps":["trace[1180014782] 'process raft request' (duration: 131.346362ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:14.02036Z","caller":"traceutil/trace.go:171","msg":"trace[1852488205] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1320; }","duration":"130.66465ms","start":"2026-07-28T09:23:13.889675Z","end":"2026-07-28T09:23:14.02034Z","steps":["trace[1852488205] 'read index received' (duration: 28.322507ms)","trace[1852488205] 'applied index is now lower than readState.Index' (duration: 102.341393ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:23:14.020411Z","caller":"traceutil/trace.go:171","msg":"trace[806784047] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"128.897433ms","start":"2026-07-28T09:23:13.891496Z","end":"2026-07-28T09:23:14.020394Z","steps":["trace[806784047] 'process raft request' (duration: 128.728057ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:14.020511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.813835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T09:23:14.020568Z","caller":"traceutil/trace.go:171","msg":"trace[2043590290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1269; }","duration":"130.885197ms","start":"2026-07-28T09:23:13.889651Z","end":"2026-07-28T09:23:14.020537Z","steps":["trace[2043590290] 'agreement among raft nodes before linearized reading' (duration: 130.793794ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:16.421668Z","caller":"traceutil/trace.go:171","msg":"trace[5531086] linearizableReadLoop","detail":"{readStateIndex:1328; appliedIndex:1327; }","duration":"499.013227ms","start":"2026-07-28T09:23:15.922627Z","end":"2026-07-28T09:23:16.42164Z","steps":["trace[5531086] 'read index received' (duration: 482.150251ms)","trace[5531086] 'applied index is now lower than readState.Index' (duration: 16.861206ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:23:16.421891Z","caller":"traceutil/trace.go:171","msg":"trace[154626743] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"550.103772ms","start":"2026-07-28T09:23:15.871776Z","end":"2026-07-28T09:23:16.421879Z","steps":["trace[154626743] 'process raft request' (duration: 533.0566ms)","trace[154626743] 'compare' (duration: 16.516615ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:23:16.421967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:15.871743Z","time spent":"550.172324ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:23:16.422158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.541365ms","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-07-28T09:23:16.422179Z","caller":"traceutil/trace.go:171","msg":"trace[181504555] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1271; }","duration":"499.602486ms","start":"2026-07-28T09:23:15.922569Z","end":"2026-07-28T09:23:16.422172Z","steps":["trace[181504555] 'agreement among raft nodes before linearized reading' (duration: 499.524804ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:16.422172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.121439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-28T09:23:16.422196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:15.922548Z","time spent":"499.643788ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-28T09:23:16.422199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.628456ms","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-07-28T09:23:16.422223Z","caller":"traceutil/trace.go:171","msg":"trace[1523851723] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:1271; }","duration":"448.189842ms","start":"2026-07-28T09:23:15.97402Z","end":"2026-07-28T09:23:16.42221Z","steps":["trace[1523851723] 'agreement among raft nodes before linearized reading' (duration: 448.021006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:16.422253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:15.973965Z","time spent":"448.282315ms","remote":"127.0.0.1:53390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.115\" "} {"level":"info","ts":"2026-07-28T09:23:16.422253Z","caller":"traceutil/trace.go:171","msg":"trace[1840093387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1271; }","duration":"393.718087ms","start":"2026-07-28T09:23:16.028521Z","end":"2026-07-28T09:23:16.422239Z","steps":["trace[1840093387] 'agreement among raft nodes before linearized reading' (duration: 393.564503ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:16.422285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:16.028501Z","time spent":"393.7775ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-28T09:23:16.422279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.09917ms","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-07-28T09:23:16.422324Z","caller":"traceutil/trace.go:171","msg":"trace[2141244544] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1271; }","duration":"394.152942ms","start":"2026-07-28T09:23:16.028156Z","end":"2026-07-28T09:23:16.422309Z","steps":["trace[2141244544] 'agreement among raft nodes before linearized reading' (duration: 394.054369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:16.422363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:16.028149Z","time spent":"394.204003ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-28T09:23:16.422366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.904838ms","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-07-28T09:23:16.422225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.484075ms","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-07-28T09:23:16.422399Z","caller":"traceutil/trace.go:171","msg":"trace[126748140] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1271; }","duration":"395.96092ms","start":"2026-07-28T09:23:16.026426Z","end":"2026-07-28T09:23:16.422387Z","steps":["trace[126748140] 'agreement among raft nodes before linearized reading' (duration: 395.884428ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:16.422419Z","caller":"traceutil/trace.go:171","msg":"trace[1612970397] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1271; }","duration":"395.720003ms","start":"2026-07-28T09:23:16.026688Z","end":"2026-07-28T09:23:16.422408Z","steps":["trace[1612970397] 'agreement among raft nodes before linearized reading' (duration: 395.420363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:16.422425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:16.02641Z","time spent":"396.008622ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-28T09:23:16.422449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:16.02667Z","time spent":"395.770364ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-28T09:23:18.671513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.077442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-28T09:23:18.671644Z","caller":"traceutil/trace.go:171","msg":"trace[1439206391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1278; }","duration":"172.192676ms","start":"2026-07-28T09:23:18.499384Z","end":"2026-07-28T09:23:18.671577Z","steps":["trace[1439206391] 'range keys from in-memory index tree' (duration: 171.860875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:18.671695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.253898ms","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":"warn","ts":"2026-07-28T09:23:18.671751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.527576ms","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-07-28T09:23:18.671773Z","caller":"traceutil/trace.go:171","msg":"trace[1360965845] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1278; }","duration":"172.395102ms","start":"2026-07-28T09:23:18.499358Z","end":"2026-07-28T09:23:18.671753Z","steps":["trace[1360965845] 'range keys from in-memory index tree' (duration: 172.04342ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:18.671818Z","caller":"traceutil/trace.go:171","msg":"trace[515950718] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1278; }","duration":"172.706412ms","start":"2026-07-28T09:23:18.499096Z","end":"2026-07-28T09:23:18.671802Z","steps":["trace[515950718] 'range keys from in-memory index tree' (duration: 172.31313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:18.671839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.686614ms","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-07-28T09:23:18.671887Z","caller":"traceutil/trace.go:171","msg":"trace[463750654] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1278; }","duration":"173.769797ms","start":"2026-07-28T09:23:18.498098Z","end":"2026-07-28T09:23:18.671867Z","steps":["trace[463750654] 'range keys from in-memory index tree' (duration: 173.570391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:18.672534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.545619ms","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-07-28T09:23:18.672639Z","caller":"traceutil/trace.go:171","msg":"trace[1934611276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1278; }","duration":"173.674153ms","start":"2026-07-28T09:23:18.498945Z","end":"2026-07-28T09:23:18.67262Z","steps":["trace[1934611276] 'range keys from in-memory index tree' (duration: 172.498166ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:20.812704Z","caller":"traceutil/trace.go:171","msg":"trace[582398596] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"103.300262ms","start":"2026-07-28T09:23:20.70937Z","end":"2026-07-28T09:23:20.81267Z","steps":["trace[582398596] 'process raft request' (duration: 103.143867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:22.610283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.928455ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T09:23:22.610367Z","caller":"traceutil/trace.go:171","msg":"trace[1246831883] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"1.793360983s","start":"2026-07-28T09:23:20.816992Z","end":"2026-07-28T09:23:22.610353Z","steps":["trace[1246831883] 'process raft request' (duration: 805.297923ms)","trace[1246831883] 'compare' (duration: 987.745759ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:23:22.610406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:20.816971Z","time spent":"1.793417875s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:23:22.719378Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.096990493s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-28T09:23:23.304457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.767849ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T09:23:23.304799Z","caller":"traceutil/trace.go:171","msg":"trace[961045837] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"2.487508301s","start":"2026-07-28T09:23:20.817259Z","end":"2026-07-28T09:23:23.304768Z","steps":["trace[961045837] 'process raft request' (duration: 1.903167104s)","trace[961045837] 'compare' (duration: 583.609474ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:23:23.304879Z","caller":"traceutil/trace.go:171","msg":"trace[2136470354] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"2.487460961s","start":"2026-07-28T09:23:20.817406Z","end":"2026-07-28T09:23:23.304867Z","steps":["trace[2136470354] 'process raft request' (duration: 2.487395609s)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:23.304902Z","caller":"traceutil/trace.go:171","msg":"trace[640337943] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"2.487574754s","start":"2026-07-28T09:23:20.817302Z","end":"2026-07-28T09:23:23.304877Z","steps":["trace[640337943] 'process raft request' (duration: 2.487358527s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:23.304946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:20.817395Z","time spent":"2.487519832s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:23:23.305032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:20.817297Z","time spent":"2.487660357s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T09:23:23.30504Z","caller":"traceutil/trace.go:171","msg":"trace[969440084] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"2.485138495s","start":"2026-07-28T09:23:20.819888Z","end":"2026-07-28T09:23:23.305027Z","steps":["trace[969440084] 'process raft request' (duration: 2.484951659s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:23.305041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:20.817252Z","time spent":"2.487663337s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:23:23.305162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:20.819874Z","time spent":"2.485218618s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:23:23.562949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.94543ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-28T09:23:23.563214Z","caller":"traceutil/trace.go:171","msg":"trace[591094241] linearizableReadLoop","detail":"{readStateIndex:1357; appliedIndex:1352; }","duration":"1.167473876s","start":"2026-07-28T09:23:22.395722Z","end":"2026-07-28T09:23:23.563196Z","steps":["trace[591094241] 'read index received' (duration: 324.071753ms)","trace[591094241] 'applied index is now lower than readState.Index' (duration: 843.401063ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:23:23.563427Z","caller":"traceutil/trace.go:171","msg":"trace[1981772012] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"1.089795661s","start":"2026-07-28T09:23:22.473545Z","end":"2026-07-28T09:23:23.563341Z","steps":["trace[1981772012] 'process raft request' (duration: 1.089528342s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:23.563624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:22.473525Z","time spent":"1.090020068s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:23:23.566461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.170736972s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T09:23:23.566523Z","caller":"traceutil/trace.go:171","msg":"trace[1255204948] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1299; }","duration":"1.170829775s","start":"2026-07-28T09:23:22.395678Z","end":"2026-07-28T09:23:23.566508Z","steps":["trace[1255204948] 'agreement among raft nodes before linearized reading' (duration: 1.16758971s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:23.566562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:22.395663Z","time spent":"1.170891526s","remote":"127.0.0.1:53360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-28T09:23:23.566599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.004727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T09:23:23.566645Z","caller":"traceutil/trace.go:171","msg":"trace[539083892] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1299; }","duration":"797.07925ms","start":"2026-07-28T09:23:22.76955Z","end":"2026-07-28T09:23:23.566629Z","steps":["trace[539083892] 'agreement among raft nodes before linearized reading' (duration: 793.769053ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:23.566677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:22.769533Z","time spent":"797.135661ms","remote":"127.0.0.1:53680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-28T09:23:24.162801Z","caller":"traceutil/trace.go:171","msg":"trace[36585788] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"110.971649ms","start":"2026-07-28T09:23:24.051808Z","end":"2026-07-28T09:23:24.162779Z","steps":["trace[36585788] 'process raft request' (duration: 110.843375ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:25.425107Z","caller":"traceutil/trace.go:171","msg":"trace[857489426] linearizableReadLoop","detail":"{readStateIndex:1363; appliedIndex:1362; }","duration":"116.756506ms","start":"2026-07-28T09:23:25.308326Z","end":"2026-07-28T09:23:25.425083Z","steps":["trace[857489426] 'read index received' (duration: 115.729502ms)","trace[857489426] 'applied index is now lower than readState.Index' (duration: 1.025743ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:23:25.425111Z","caller":"traceutil/trace.go:171","msg":"trace[398891719] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"404.997373ms","start":"2026-07-28T09:23:25.020088Z","end":"2026-07-28T09:23:25.425085Z","steps":["trace[398891719] 'process raft request' (duration: 403.948979ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:25.425253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:25.020065Z","time spent":"405.124407ms","remote":"127.0.0.1:53442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-28T09:23:25.425365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.019584ms","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-07-28T09:23:25.4254Z","caller":"traceutil/trace.go:171","msg":"trace[1073155167] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1304; }","duration":"117.102287ms","start":"2026-07-28T09:23:25.308287Z","end":"2026-07-28T09:23:25.425389Z","steps":["trace[1073155167] 'agreement among raft nodes before linearized reading' (duration: 116.932452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:25.425657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.669953ms","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-07-28T09:23:25.425693Z","caller":"traceutil/trace.go:171","msg":"trace[974438571] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1304; }","duration":"116.720175ms","start":"2026-07-28T09:23:25.308962Z","end":"2026-07-28T09:23:25.425682Z","steps":["trace[974438571] 'agreement among raft nodes before linearized reading' (duration: 116.55521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:25.425721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.297204ms","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-07-28T09:23:25.425801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.949942ms","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-07-28T09:23:25.425836Z","caller":"traceutil/trace.go:171","msg":"trace[547584144] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1304; }","duration":"116.999633ms","start":"2026-07-28T09:23:25.308827Z","end":"2026-07-28T09:23:25.425826Z","steps":["trace[547584144] 'agreement among raft nodes before linearized reading' (duration: 116.907291ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:25.425874Z","caller":"traceutil/trace.go:171","msg":"trace[969769676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1304; }","duration":"117.50385ms","start":"2026-07-28T09:23:25.308318Z","end":"2026-07-28T09:23:25.425822Z","steps":["trace[969769676] 'agreement among raft nodes before linearized reading' (duration: 117.049906ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:28.708455Z","caller":"traceutil/trace.go:171","msg":"trace[1670070715] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"132.578046ms","start":"2026-07-28T09:23:28.575847Z","end":"2026-07-28T09:23:28.708425Z","steps":["trace[1670070715] 'process raft request' (duration: 92.204094ms)","trace[1670070715] 'compare' (duration: 40.264139ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:23:28.709058Z","caller":"traceutil/trace.go:171","msg":"trace[94774488] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"132.992979ms","start":"2026-07-28T09:23:28.576049Z","end":"2026-07-28T09:23:28.709042Z","steps":["trace[94774488] 'process raft request' (duration: 132.70244ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:28.709287Z","caller":"traceutil/trace.go:171","msg":"trace[586004981] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"129.474746ms","start":"2026-07-28T09:23:28.579772Z","end":"2026-07-28T09:23:28.709246Z","steps":["trace[586004981] 'process raft request' (duration: 129.099264ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:29.693722Z","caller":"traceutil/trace.go:171","msg":"trace[1382581978] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"152.850061ms","start":"2026-07-28T09:23:29.540848Z","end":"2026-07-28T09:23:29.693698Z","steps":["trace[1382581978] 'process raft request' (duration: 152.737507ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:29.693766Z","caller":"traceutil/trace.go:171","msg":"trace[436802485] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"152.880831ms","start":"2026-07-28T09:23:29.540861Z","end":"2026-07-28T09:23:29.693742Z","steps":["trace[436802485] 'process raft request' (duration: 152.799698ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:29.693954Z","caller":"traceutil/trace.go:171","msg":"trace[918505184] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"154.07611ms","start":"2026-07-28T09:23:29.539845Z","end":"2026-07-28T09:23:29.693921Z","steps":["trace[918505184] 'process raft request' (duration: 65.012017ms)","trace[918505184] 'compare' (duration: 88.568717ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:23:31.03286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.207275ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T09:23:31.032972Z","caller":"traceutil/trace.go:171","msg":"trace[1424725566] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"199.199185ms","start":"2026-07-28T09:23:30.833753Z","end":"2026-07-28T09:23:31.032952Z","steps":["trace[1424725566] 'process raft request' (duration: 57.816015ms)","trace[1424725566] 'compare' (duration: 141.055979ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:23:32.047016Z","caller":"traceutil/trace.go:171","msg":"trace[28374602] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"502.087294ms","start":"2026-07-28T09:23:31.544899Z","end":"2026-07-28T09:23:32.046986Z","steps":["trace[28374602] 'process raft request' (duration: 501.896798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:32.047261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:31.544877Z","time spent":"502.221958ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:23:33.080849Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.034120255s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-28T09:23:33.182134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.163022ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-28T09:23:33.182344Z","caller":"traceutil/trace.go:171","msg":"trace[858460374] linearizableReadLoop","detail":"{readStateIndex:1416; appliedIndex:1414; }","duration":"1.571915089s","start":"2026-07-28T09:23:31.610415Z","end":"2026-07-28T09:23:33.18233Z","steps":["trace[858460374] 'read index received' (duration: 436.343353ms)","trace[858460374] 'applied index is now lower than readState.Index' (duration: 1.135570956s)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:23:33.182439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.572020063s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T09:23:33.182408Z","caller":"traceutil/trace.go:171","msg":"trace[66860690] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"1.48265208s","start":"2026-07-28T09:23:31.699727Z","end":"2026-07-28T09:23:33.182379Z","steps":["trace[66860690] 'process raft request' (duration: 1.482490225s)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:33.182472Z","caller":"traceutil/trace.go:171","msg":"trace[709375658] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1356; }","duration":"1.572095166s","start":"2026-07-28T09:23:31.610366Z","end":"2026-07-28T09:23:33.182462Z","steps":["trace[709375658] 'agreement among raft nodes before linearized reading' (duration: 1.572033664s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:33.1825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:31.610347Z","time spent":"1.572144578s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-07-28T09:23:33.182555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:31.699699Z","time spent":"1.482783744s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:23:33.182721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.530503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T09:23:33.182808Z","caller":"traceutil/trace.go:171","msg":"trace[696244174] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1356; }","duration":"637.675598ms","start":"2026-07-28T09:23:32.545112Z","end":"2026-07-28T09:23:33.182787Z","steps":["trace[696244174] 'agreement among raft nodes before linearized reading' (duration: 637.515143ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:33.182869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:32.545096Z","time spent":"637.7578ms","remote":"127.0.0.1:53552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":5,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-07-28T09:23:33.182906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.647756ms","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:530"} {"level":"info","ts":"2026-07-28T09:23:33.182927Z","caller":"traceutil/trace.go:171","msg":"trace[1534164326] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1356; }","duration":"925.700058ms","start":"2026-07-28T09:23:32.257221Z","end":"2026-07-28T09:23:33.182921Z","steps":["trace[1534164326] 'agreement among raft nodes before linearized reading' (duration: 925.623675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:33.182946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:32.257203Z","time spent":"925.738879ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-28T09:23:33.182955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.48450368s","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-07-28T09:23:33.183005Z","caller":"traceutil/trace.go:171","msg":"trace[1247102670] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1356; }","duration":"1.484567003s","start":"2026-07-28T09:23:31.698424Z","end":"2026-07-28T09:23:33.182991Z","steps":["trace[1247102670] 'agreement among raft nodes before linearized reading' (duration: 1.484419538s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:33.183048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:31.698415Z","time spent":"1.484625534s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-28T09:23:33.182829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.057469467s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T09:23:33.183113Z","caller":"traceutil/trace.go:171","msg":"trace[48707206] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1356; }","duration":"1.057784658s","start":"2026-07-28T09:23:32.125322Z","end":"2026-07-28T09:23:33.183106Z","steps":["trace[48707206] 'agreement among raft nodes before linearized reading' (duration: 1.057480158s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:33.183137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:32.125307Z","time spent":"1.057823769s","remote":"127.0.0.1:53678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-07-28T09:23:33.183181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.492531ms","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-07-28T09:23:33.183236Z","caller":"traceutil/trace.go:171","msg":"trace[1454340868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1356; }","duration":"379.574043ms","start":"2026-07-28T09:23:32.803648Z","end":"2026-07-28T09:23:33.183222Z","steps":["trace[1454340868] 'agreement among raft nodes before linearized reading' (duration: 379.445509ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:33.183285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:32.803632Z","time spent":"379.639375ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-28T09:23:33.183321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.485153291s","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-07-28T09:23:33.183348Z","caller":"traceutil/trace.go:171","msg":"trace[645339399] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1356; }","duration":"1.485207393s","start":"2026-07-28T09:23:31.698135Z","end":"2026-07-28T09:23:33.183342Z","steps":["trace[645339399] 'agreement among raft nodes before linearized reading' (duration: 1.485141481s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:33.183366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:31.698119Z","time spent":"1.485242504s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-28T09:23:33.183204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.629757ms","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:524"} {"level":"info","ts":"2026-07-28T09:23:33.1834Z","caller":"traceutil/trace.go:171","msg":"trace[305451640] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1356; }","duration":"136.860255ms","start":"2026-07-28T09:23:33.046529Z","end":"2026-07-28T09:23:33.183389Z","steps":["trace[305451640] 'agreement among raft nodes before linearized reading' (duration: 136.451051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:33.183257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.485644677s","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-07-28T09:23:33.183503Z","caller":"traceutil/trace.go:171","msg":"trace[1704854094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1356; }","duration":"1.486014519s","start":"2026-07-28T09:23:31.69747Z","end":"2026-07-28T09:23:33.183484Z","steps":["trace[1704854094] 'agreement among raft nodes before linearized reading' (duration: 1.485188572s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:33.183705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:31.697456Z","time spent":"1.486231476s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-28T09:23:33.368542Z","caller":"traceutil/trace.go:171","msg":"trace[1743638408] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"181.047349ms","start":"2026-07-28T09:23:33.187459Z","end":"2026-07-28T09:23:33.368506Z","steps":["trace[1743638408] 'process raft request' (duration: 118.686548ms)","trace[1743638408] 'compare' (duration: 62.185016ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:23:33.374868Z","caller":"traceutil/trace.go:171","msg":"trace[993107757] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"186.451574ms","start":"2026-07-28T09:23:33.188393Z","end":"2026-07-28T09:23:33.374845Z","steps":["trace[993107757] 'process raft request' (duration: 186.418233ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:33.374932Z","caller":"traceutil/trace.go:171","msg":"trace[129840480] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"186.824366ms","start":"2026-07-28T09:23:33.18809Z","end":"2026-07-28T09:23:33.374915Z","steps":["trace[129840480] 'process raft request' (duration: 186.657151ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:33.375116Z","caller":"traceutil/trace.go:171","msg":"trace[1709282909] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"186.876798ms","start":"2026-07-28T09:23:33.188155Z","end":"2026-07-28T09:23:33.375032Z","steps":["trace[1709282909] 'process raft request' (duration: 186.62657ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:33.375307Z","caller":"traceutil/trace.go:171","msg":"trace[1290414167] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"187.171677ms","start":"2026-07-28T09:23:33.187672Z","end":"2026-07-28T09:23:33.374844Z","steps":["trace[1290414167] 'process raft request' (duration: 186.928289ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:33.375263Z","caller":"traceutil/trace.go:171","msg":"trace[1932217309] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"187.56231ms","start":"2026-07-28T09:23:33.18769Z","end":"2026-07-28T09:23:33.375252Z","steps":["trace[1932217309] 'process raft request' (duration: 187.024182ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:35.691117Z","caller":"traceutil/trace.go:171","msg":"trace[1609822407] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"268.532381ms","start":"2026-07-28T09:23:35.422561Z","end":"2026-07-28T09:23:35.691094Z","steps":["trace[1609822407] 'process raft request' (duration: 268.381716ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:35.77188Z","caller":"traceutil/trace.go:171","msg":"trace[350412156] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"349.12617ms","start":"2026-07-28T09:23:35.422728Z","end":"2026-07-28T09:23:35.771854Z","steps":["trace[350412156] 'process raft request' (duration: 348.976935ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:35.771935Z","caller":"traceutil/trace.go:171","msg":"trace[1951990164] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"349.286246ms","start":"2026-07-28T09:23:35.422617Z","end":"2026-07-28T09:23:35.771904Z","steps":["trace[1951990164] 'process raft request' (duration: 348.911403ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:35.772084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:35.422593Z","time spent":"349.40554ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:23:35.772074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:35.42272Z","time spent":"349.268955ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T09:23:35.772079Z","caller":"traceutil/trace.go:171","msg":"trace[116810055] linearizableReadLoop","detail":"{readStateIndex:1431; appliedIndex:1427; }","duration":"251.322837ms","start":"2026-07-28T09:23:35.520729Z","end":"2026-07-28T09:23:35.772052Z","steps":["trace[116810055] 'read index received' (duration: 170.279943ms)","trace[116810055] 'applied index is now lower than readState.Index' (duration: 81.040934ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:23:35.772479Z","caller":"traceutil/trace.go:171","msg":"trace[77522956] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"349.690849ms","start":"2026-07-28T09:23:35.422773Z","end":"2026-07-28T09:23:35.772464Z","steps":["trace[77522956] 'process raft request' (duration: 349.036027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:35.772505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.773701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-28T09:23:35.772568Z","caller":"traceutil/trace.go:171","msg":"trace[815930348] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1370; }","duration":"251.868204ms","start":"2026-07-28T09:23:35.520681Z","end":"2026-07-28T09:23:35.772549Z","steps":["trace[815930348] 'agreement among raft nodes before linearized reading' (duration: 251.702389ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:35.772707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:35.422756Z","time spent":"349.775071ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:23:35.77278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.172195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-07-28T09:23:35.77284Z","caller":"traceutil/trace.go:171","msg":"trace[313698096] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1370; }","duration":"248.257218ms","start":"2026-07-28T09:23:35.524567Z","end":"2026-07-28T09:23:35.772824Z","steps":["trace[313698096] 'agreement among raft nodes before linearized reading' (duration: 247.753272ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:35.929966Z","caller":"traceutil/trace.go:171","msg":"trace[1677965122] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"153.349747ms","start":"2026-07-28T09:23:35.776584Z","end":"2026-07-28T09:23:35.929934Z","steps":["trace[1677965122] 'process raft request' (duration: 67.828278ms)","trace[1677965122] 'compare' (duration: 85.374344ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:23:35.937915Z","caller":"traceutil/trace.go:171","msg":"trace[403136262] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"160.928781ms","start":"2026-07-28T09:23:35.776955Z","end":"2026-07-28T09:23:35.937883Z","steps":["trace[403136262] 'process raft request' (duration: 160.798207ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:36.379367Z","caller":"traceutil/trace.go:171","msg":"trace[954555612] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"299.122337ms","start":"2026-07-28T09:23:36.080214Z","end":"2026-07-28T09:23:36.379337Z","steps":["trace[954555612] 'process raft request' (duration: 298.940572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:36.660786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.419604ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T09:23:36.660868Z","caller":"traceutil/trace.go:171","msg":"trace[985921884] linearizableReadLoop","detail":"{readStateIndex:1436; appliedIndex:1434; }","duration":"530.77252ms","start":"2026-07-28T09:23:36.130083Z","end":"2026-07-28T09:23:36.660856Z","steps":["trace[985921884] 'read index received' (duration: 249.046583ms)","trace[985921884] 'applied index is now lower than readState.Index' (duration: 281.724577ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:23:36.660934Z","caller":"traceutil/trace.go:171","msg":"trace[1788508653] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"578.015993ms","start":"2026-07-28T09:23:36.082909Z","end":"2026-07-28T09:23:36.660925Z","steps":["trace[1788508653] 'process raft request' (duration: 373.091333ms)","trace[1788508653] 'compare' (duration: 204.164376ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:23:36.66102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.583248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-28T09:23:36.661082Z","caller":"traceutil/trace.go:171","msg":"trace[219234041] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1374; }","duration":"279.688152ms","start":"2026-07-28T09:23:36.381377Z","end":"2026-07-28T09:23:36.661066Z","steps":["trace[219234041] 'agreement among raft nodes before linearized reading' (duration: 279.559757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:36.661085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.006727ms","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-07-28T09:23:36.661132Z","caller":"traceutil/trace.go:171","msg":"trace[181837910] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1374; }","duration":"531.10211ms","start":"2026-07-28T09:23:36.130018Z","end":"2026-07-28T09:23:36.66112Z","steps":["trace[181837910] 'agreement among raft nodes before linearized reading' (duration: 530.933505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:36.660974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:36.082885Z","time spent":"578.068725ms","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:23:36.661175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:23:36.129992Z","time spent":"531.174663ms","remote":"127.0.0.1:53662","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-07-28T09:23:39.841742Z","caller":"traceutil/trace.go:171","msg":"trace[2034958653] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"136.948197ms","start":"2026-07-28T09:23:39.704774Z","end":"2026-07-28T09:23:39.841722Z","steps":["trace[2034958653] 'process raft request' (duration: 136.42117ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:23:40.078068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.656284ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T09:23:40.078181Z","caller":"traceutil/trace.go:171","msg":"trace[1853512702] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"233.15524ms","start":"2026-07-28T09:23:39.845004Z","end":"2026-07-28T09:23:40.07816Z","steps":["trace[1853512702] 'process raft request' (duration: 80.30192ms)","trace[1853512702] 'compare' (duration: 152.302622ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:23:40.080007Z","caller":"traceutil/trace.go:171","msg":"trace[1825006366] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"234.606857ms","start":"2026-07-28T09:23:39.845384Z","end":"2026-07-28T09:23:40.079991Z","steps":["trace[1825006366] 'process raft request' (duration: 234.396561ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:40.080197Z","caller":"traceutil/trace.go:171","msg":"trace[556049867] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"233.958266ms","start":"2026-07-28T09:23:39.846172Z","end":"2026-07-28T09:23:40.080131Z","steps":["trace[556049867] 'process raft request' (duration: 233.742319ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:46.313834Z","caller":"traceutil/trace.go:171","msg":"trace[1508877977] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"185.402409ms","start":"2026-07-28T09:23:46.128402Z","end":"2026-07-28T09:23:46.313805Z","steps":["trace[1508877977] 'process raft request' (duration: 160.086713ms)","trace[1508877977] 'compare' (duration: 24.998896ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:23:46.314024Z","caller":"traceutil/trace.go:171","msg":"trace[1108827944] linearizableReadLoop","detail":"{readStateIndex:1483; appliedIndex:1480; }","duration":"180.348736ms","start":"2026-07-28T09:23:46.133654Z","end":"2026-07-28T09:23:46.314003Z","steps":["trace[1108827944] 'read index received' (duration: 45.546138ms)","trace[1108827944] 'applied index is now lower than readState.Index' (duration: 134.801747ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:23:46.314112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.436538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T09:23:46.314144Z","caller":"traceutil/trace.go:171","msg":"trace[384164937] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1419; }","duration":"180.522601ms","start":"2026-07-28T09:23:46.133612Z","end":"2026-07-28T09:23:46.314134Z","steps":["trace[384164937] 'agreement among raft nodes before linearized reading' (duration: 180.436778ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:46.314299Z","caller":"traceutil/trace.go:171","msg":"trace[1005840184] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"185.472941ms","start":"2026-07-28T09:23:46.128814Z","end":"2026-07-28T09:23:46.314287Z","steps":["trace[1005840184] 'process raft request' (duration: 184.937493ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:23:48.513679Z","caller":"traceutil/trace.go:171","msg":"trace[1794879318] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"112.915642ms","start":"2026-07-28T09:23:48.400737Z","end":"2026-07-28T09:23:48.513653Z","steps":["trace[1794879318] 'process raft request' (duration: 33.634535ms)","trace[1794879318] 'compare' (duration: 79.130452ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:24:04.485437Z","caller":"traceutil/trace.go:171","msg":"trace[256961115] transaction","detail":"{read_only:false; response_revision:1498; number_of_response:1; }","duration":"184.17203ms","start":"2026-07-28T09:24:04.301241Z","end":"2026-07-28T09:24:04.485413Z","steps":["trace[256961115] 'process raft request' (duration: 183.968474ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:24:21.368309Z","caller":"traceutil/trace.go:171","msg":"trace[2075160378] transaction","detail":"{read_only:false; response_revision:1577; number_of_response:1; }","duration":"256.997129ms","start":"2026-07-28T09:24:21.111291Z","end":"2026-07-28T09:24:21.368288Z","steps":["trace[2075160378] 'process raft request' (duration: 256.836424ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:24:21.885954Z","caller":"traceutil/trace.go:171","msg":"trace[386687841] linearizableReadLoop","detail":"{readStateIndex:1650; appliedIndex:1649; }","duration":"133.851168ms","start":"2026-07-28T09:24:21.752084Z","end":"2026-07-28T09:24:21.885935Z","steps":["trace[386687841] 'read index received' (duration: 133.643041ms)","trace[386687841] 'applied index is now lower than readState.Index' (duration: 207.437µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:24:21.886006Z","caller":"traceutil/trace.go:171","msg":"trace[567763714] transaction","detail":"{read_only:false; response_revision:1579; number_of_response:1; }","duration":"137.861737ms","start":"2026-07-28T09:24:21.748112Z","end":"2026-07-28T09:24:21.885973Z","steps":["trace[567763714] 'process raft request' (duration: 137.668541ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:24:21.886125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.028393ms","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-07-28T09:24:21.886156Z","caller":"traceutil/trace.go:171","msg":"trace[1879670067] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1579; }","duration":"134.101555ms","start":"2026-07-28T09:24:21.752046Z","end":"2026-07-28T09:24:21.886147Z","steps":["trace[1879670067] 'agreement among raft nodes before linearized reading' (duration: 133.973611ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:24:22.289377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.384234ms","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-07-28T09:24:22.289468Z","caller":"traceutil/trace.go:171","msg":"trace[805373091] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1580; }","duration":"174.526169ms","start":"2026-07-28T09:24:22.114922Z","end":"2026-07-28T09:24:22.289449Z","steps":["trace[805373091] 'range keys from in-memory index tree' (duration: 174.193238ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:24:31.366419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.966147ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-07-28T09:24:37.045776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.323723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-07-28T09:24:37.0459Z","caller":"traceutil/trace.go:171","msg":"trace[1368405785] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1649; }","duration":"140.488799ms","start":"2026-07-28T09:24:36.905391Z","end":"2026-07-28T09:24:37.04588Z","steps":["trace[1368405785] 'range keys from in-memory index tree' (duration: 140.109016ms)"],"step_count":1}