{"level":"warn","ts":"2026-07-09T15:34:00.558749Z","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-09T15:34:00.558831Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.124:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.124:2380","--initial-cluster=instance=https://199.19.213.124:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.124:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.124: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-09T15:34:00.558891Z","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-09T15:34:00.558901Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.124:2380"]} {"level":"info","ts":"2026-07-09T15:34:00.55892Z","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-09T15:34:00.559539Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.124:2379"]} {"level":"info","ts":"2026-07-09T15:34:00.559664Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.124:2380"],"listen-peer-urls":["https://199.19.213.124:2380"],"advertise-client-urls":["https://199.19.213.124:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.124:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.124: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-09T15:34:00.564176Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.242268ms"} {"level":"info","ts":"2026-07-09T15:34:00.570924Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6894f7e0c0041bce","cluster-id":"db11c089a44de10a"} {"level":"info","ts":"2026-07-09T15:34:00.570994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce switched to configuration voters=()"} {"level":"info","ts":"2026-07-09T15:34:00.571015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce became follower at term 0"} {"level":"info","ts":"2026-07-09T15:34:00.571028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6894f7e0c0041bce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-09T15:34:00.571041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce became follower at term 1"} {"level":"info","ts":"2026-07-09T15:34:00.571079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce switched to configuration voters=(7535920621163912142)"} {"level":"warn","ts":"2026-07-09T15:34:00.577544Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-09T15:34:00.578863Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-09T15:34:00.580069Z","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-09T15:34:00.583117Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6894f7e0c0041bce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-09T15:34:00.583386Z","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-09T15:34:00.583542Z","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-09T15:34:00.583599Z","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-09T15:34:00.583538Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6894f7e0c0041bce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-09T15:34:00.584706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce switched to configuration voters=(7535920621163912142)"} {"level":"info","ts":"2026-07-09T15:34:00.585994Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"db11c089a44de10a","local-member-id":"6894f7e0c0041bce","added-peer-id":"6894f7e0c0041bce","added-peer-peer-urls":["https://199.19.213.124:2380"]} {"level":"info","ts":"2026-07-09T15:34:00.589243Z","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-09T15:34:00.589386Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.124:2380"} {"level":"info","ts":"2026-07-09T15:34:00.589427Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.124:2380"} {"level":"info","ts":"2026-07-09T15:34:00.589732Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6894f7e0c0041bce","initial-advertise-peer-urls":["https://199.19.213.124:2380"],"listen-peer-urls":["https://199.19.213.124:2380"],"advertise-client-urls":["https://199.19.213.124:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.124:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-09T15:34:00.589793Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-09T15:34:01.371955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce is starting a new election at term 1"} {"level":"info","ts":"2026-07-09T15:34:01.372012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce became pre-candidate at term 1"} {"level":"info","ts":"2026-07-09T15:34:01.372038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce received MsgPreVoteResp from 6894f7e0c0041bce at term 1"} {"level":"info","ts":"2026-07-09T15:34:01.372056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce became candidate at term 2"} {"level":"info","ts":"2026-07-09T15:34:01.372069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce received MsgVoteResp from 6894f7e0c0041bce at term 2"} {"level":"info","ts":"2026-07-09T15:34:01.372084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce became leader at term 2"} {"level":"info","ts":"2026-07-09T15:34:01.372097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6894f7e0c0041bce elected leader 6894f7e0c0041bce at term 2"} {"level":"info","ts":"2026-07-09T15:34:01.375571Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6894f7e0c0041bce","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.124:2379]}","request-path":"/0/members/6894f7e0c0041bce/attributes","cluster-id":"db11c089a44de10a","publish-timeout":"7s"} {"level":"info","ts":"2026-07-09T15:34:01.375583Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T15:34:01.375614Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T15:34:01.375676Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T15:34:01.375918Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-09T15:34:01.376023Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-09T15:34:01.376563Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"db11c089a44de10a","local-member-id":"6894f7e0c0041bce","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T15:34:01.376752Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T15:34:01.376834Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T15:34:01.37926Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-09T15:34:01.379719Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.124:2379"} {"level":"info","ts":"2026-07-09T15:34:25.200867Z","caller":"traceutil/trace.go:171","msg":"trace[1971575458] linearizableReadLoop","detail":"{readStateIndex:479; appliedIndex:478; }","duration":"208.017349ms","start":"2026-07-09T15:34:24.992823Z","end":"2026-07-09T15:34:25.200841Z","steps":["trace[1971575458] 'read index received' (duration: 207.789742ms)","trace[1971575458] 'applied index is now lower than readState.Index' (duration: 226.517µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:34:25.20092Z","caller":"traceutil/trace.go:171","msg":"trace[718186604] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"318.968871ms","start":"2026-07-09T15:34:24.88191Z","end":"2026-07-09T15:34:25.200878Z","steps":["trace[718186604] 'process raft request' (duration: 318.783975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:34:25.201125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.255987ms","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-09T15:34:25.201231Z","caller":"traceutil/trace.go:171","msg":"trace[2117410950] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:462; }","duration":"208.405993ms","start":"2026-07-09T15:34:24.992802Z","end":"2026-07-09T15:34:25.201208Z","steps":["trace[2117410950] 'agreement among raft nodes before linearized reading' (duration: 208.155094ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:34:25.201617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:34:24.881886Z","time spent":"319.131296ms","remote":"127.0.0.1:37612","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-09T15:34:38.201145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.904026ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:34:38.201548Z","caller":"traceutil/trace.go:171","msg":"trace[961730408] transaction","detail":"{read_only:false; number_of_response:1; response_revision:531; }","duration":"727.083239ms","start":"2026-07-09T15:34:37.474404Z","end":"2026-07-09T15:34:38.201487Z","steps":["trace[961730408] 'process raft request' (duration: 726.850672ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:34:38.201646Z","caller":"traceutil/trace.go:171","msg":"trace[1771005666] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"726.007947ms","start":"2026-07-09T15:34:37.475619Z","end":"2026-07-09T15:34:38.201627Z","steps":["trace[1771005666] 'process raft request' (duration: 725.919494ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:34:38.201615Z","caller":"traceutil/trace.go:171","msg":"trace[1841175789] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"727.274235ms","start":"2026-07-09T15:34:37.474282Z","end":"2026-07-09T15:34:38.201556Z","steps":["trace[1841175789] 'process raft request' (duration: 490.678357ms)","trace[1841175789] 'compare' (duration: 235.503325ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:34:38.201696Z","caller":"traceutil/trace.go:171","msg":"trace[1495152094] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"726.593074ms","start":"2026-07-09T15:34:37.475078Z","end":"2026-07-09T15:34:38.201671Z","steps":["trace[1495152094] 'process raft request' (duration: 726.245894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:34:38.201735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:34:37.475614Z","time spent":"726.086909ms","remote":"127.0.0.1:37876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T15:34:38.201752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:34:37.474399Z","time spent":"727.262165ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6937,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:34:38.201782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:34:37.475069Z","time spent":"726.673098ms","remote":"127.0.0.1:37876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T15:34:38.201805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:34:37.474268Z","time spent":"727.44108ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:34:38.205796Z","caller":"traceutil/trace.go:171","msg":"trace[1364377360] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"723.777509ms","start":"2026-07-09T15:34:37.481995Z","end":"2026-07-09T15:34:38.205772Z","steps":["trace[1364377360] 'process raft request' (duration: 723.507051ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:34:38.205636Z","caller":"traceutil/trace.go:171","msg":"trace[2010390044] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"725.714167ms","start":"2026-07-09T15:34:37.479867Z","end":"2026-07-09T15:34:38.205582Z","steps":["trace[2010390044] 'process raft request' (duration: 725.47894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:34:38.206559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:34:37.479851Z","time spent":"726.596594ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:34:38.20721Z","caller":"traceutil/trace.go:171","msg":"trace[1935453107] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"724.733468ms","start":"2026-07-09T15:34:37.48204Z","end":"2026-07-09T15:34:38.206773Z","steps":["trace[1935453107] 'process raft request' (duration: 723.592204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:34:38.207574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:34:37.482036Z","time spent":"725.263024ms","remote":"127.0.0.1:37398","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-09T15:34:38.209185Z","caller":"traceutil/trace.go:171","msg":"trace[844801544] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"726.47793ms","start":"2026-07-09T15:34:37.482679Z","end":"2026-07-09T15:34:38.209156Z","steps":["trace[844801544] 'process raft request' (duration: 724.513361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:34:38.209361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:34:37.482672Z","time spent":"726.589843ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T15:34:38.21013Z","caller":"traceutil/trace.go:171","msg":"trace[1321758117] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"726.496132ms","start":"2026-07-09T15:34:37.483595Z","end":"2026-07-09T15:34:38.210092Z","steps":["trace[1321758117] 'process raft request' (duration: 725.008036ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:34:38.210351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:34:37.483584Z","time spent":"726.603635ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T15:34:38.211017Z","caller":"traceutil/trace.go:171","msg":"trace[1980006464] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"723.47539ms","start":"2026-07-09T15:34:37.487518Z","end":"2026-07-09T15:34:38.210993Z","steps":["trace[1980006464] 'process raft request' (duration: 721.933942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:34:38.211229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:34:37.487509Z","time spent":"723.578882ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T15:34:38.211938Z","caller":"traceutil/trace.go:171","msg":"trace[14914898] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"719.282321ms","start":"2026-07-09T15:34:37.492566Z","end":"2026-07-09T15:34:38.211848Z","steps":["trace[14914898] 'process raft request' (duration: 718.980582ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:34:38.212737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:34:37.481982Z","time spent":"724.384328ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T15:34:38.216097Z","caller":"traceutil/trace.go:171","msg":"trace[402556287] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"703.525854ms","start":"2026-07-09T15:34:37.509196Z","end":"2026-07-09T15:34:38.212722Z","steps":["trace[402556287] 'process raft request' (duration: 702.617006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:34:38.216206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:34:37.509187Z","time spent":"706.952487ms","remote":"127.0.0.1:37398","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-09T15:34:38.216675Z","caller":"traceutil/trace.go:171","msg":"trace[1315115783] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"719.145077ms","start":"2026-07-09T15:34:37.492806Z","end":"2026-07-09T15:34:38.211951Z","steps":["trace[1315115783] 'process raft request' (duration: 718.880359ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:34:38.216731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:34:37.492798Z","time spent":"723.909503ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T15:34:38.216897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:34:37.492557Z","time spent":"719.440877ms","remote":"127.0.0.1:37398","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-09T15:34:38.217388Z","caller":"traceutil/trace.go:171","msg":"trace[211680779] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"576.426852ms","start":"2026-07-09T15:34:37.640932Z","end":"2026-07-09T15:34:38.217359Z","steps":["trace[211680779] 'process raft request' (duration: 576.02766ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:34:38.217487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:34:37.640903Z","time spent":"576.539175ms","remote":"127.0.0.1:37398","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-09T15:34:38.218007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.512508ms","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-09T15:34:38.218035Z","caller":"traceutil/trace.go:171","msg":"trace[2019401360] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:543; }","duration":"424.5946ms","start":"2026-07-09T15:34:37.793433Z","end":"2026-07-09T15:34:38.218028Z","steps":["trace[2019401360] 'agreement among raft nodes before linearized reading' (duration: 424.232379ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:34:38.218051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:34:37.793415Z","time spent":"424.632491ms","remote":"127.0.0.1:37612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-09T15:34:38.220219Z","caller":"traceutil/trace.go:171","msg":"trace[525313419] linearizableReadLoop","detail":"{readStateIndex:564; appliedIndex:550; }","duration":"423.705392ms","start":"2026-07-09T15:34:37.793496Z","end":"2026-07-09T15:34:38.217202Z","steps":["trace[525313419] 'read index received' (duration: 171.546112ms)","trace[525313419] 'applied index is now lower than readState.Index' (duration: 252.15853ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:34:38.220495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.263647ms","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-09T15:34:38.220526Z","caller":"traceutil/trace.go:171","msg":"trace[1054827173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:545; }","duration":"260.298028ms","start":"2026-07-09T15:34:37.960218Z","end":"2026-07-09T15:34:38.220516Z","steps":["trace[1054827173] 'agreement among raft nodes before linearized reading' (duration: 260.167754ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:34:44.327297Z","caller":"traceutil/trace.go:171","msg":"trace[1159030935] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"332.973766ms","start":"2026-07-09T15:34:43.994288Z","end":"2026-07-09T15:34:44.327262Z","steps":["trace[1159030935] 'process raft request' (duration: 332.040017ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:34:44.327589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:34:43.994257Z","time spent":"333.258754ms","remote":"127.0.0.1:37430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:34:44.672236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.466282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:34:44.672336Z","caller":"traceutil/trace.go:171","msg":"trace[5003197] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"209.67276ms","start":"2026-07-09T15:34:44.462646Z","end":"2026-07-09T15:34:44.672319Z","steps":["trace[5003197] 'process raft request' (duration: 83.043433ms)","trace[5003197] 'compare' (duration: 126.276137ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:34:45.605074Z","caller":"traceutil/trace.go:171","msg":"trace[1540601019] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"316.867986ms","start":"2026-07-09T15:34:45.288186Z","end":"2026-07-09T15:34:45.605054Z","steps":["trace[1540601019] 'read index received' (duration: 316.140574ms)","trace[1540601019] 'applied index is now lower than readState.Index' (duration: 726.602µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:34:45.605163Z","caller":"traceutil/trace.go:171","msg":"trace[1266830999] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"770.56093ms","start":"2026-07-09T15:34:44.834587Z","end":"2026-07-09T15:34:45.605148Z","steps":["trace[1266830999] 'process raft request' (duration: 769.923391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:34:45.605338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.134365ms","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-09T15:34:45.605414Z","caller":"traceutil/trace.go:171","msg":"trace[1067427107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:638; }","duration":"317.255738ms","start":"2026-07-09T15:34:45.288137Z","end":"2026-07-09T15:34:45.605393Z","steps":["trace[1067427107] 'agreement among raft nodes before linearized reading' (duration: 317.037352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:34:45.605419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.974679ms","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-09T15:34:45.60541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.295426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T15:34:45.605448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:34:45.288117Z","time spent":"317.32525ms","remote":"127.0.0.1:37612","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-09T15:34:45.605459Z","caller":"traceutil/trace.go:171","msg":"trace[236558355] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:638; }","duration":"313.061241ms","start":"2026-07-09T15:34:45.292386Z","end":"2026-07-09T15:34:45.605448Z","steps":["trace[236558355] 'agreement among raft nodes before linearized reading' (duration: 312.930877ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:34:45.605484Z","caller":"traceutil/trace.go:171","msg":"trace[215106612] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"227.42482ms","start":"2026-07-09T15:34:45.378042Z","end":"2026-07-09T15:34:45.605467Z","steps":["trace[215106612] 'agreement among raft nodes before linearized reading' (duration: 227.286845ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:34:45.605489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:34:45.292364Z","time spent":"313.117623ms","remote":"127.0.0.1:37612","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-09T15:34:45.605385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:34:44.834555Z","time spent":"770.746656ms","remote":"127.0.0.1:37430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:34:46.867136Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003714013727326362,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T15:34:47.367857Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003714013727326362,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T15:34:47.397136Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.734452264s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T15:34:47.397522Z","caller":"traceutil/trace.go:171","msg":"trace[727176902] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"1.736690131s","start":"2026-07-09T15:34:45.660796Z","end":"2026-07-09T15:34:47.397486Z","steps":["trace[727176902] 'process raft request' (duration: 1.736541287s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:34:47.397766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:34:45.660776Z","time spent":"1.736923249s","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T15:34:47.429775Z","caller":"traceutil/trace.go:171","msg":"trace[1894375606] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:663; }","duration":"1.063220921s","start":"2026-07-09T15:34:46.36653Z","end":"2026-07-09T15:34:47.429751Z","steps":["trace[1894375606] 'read index received' (duration: 1.030834807s)","trace[1894375606] 'applied index is now lower than readState.Index' (duration: 32.385084ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:34:47.429907Z","caller":"traceutil/trace.go:171","msg":"trace[144340942] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"184.991599ms","start":"2026-07-09T15:34:47.244896Z","end":"2026-07-09T15:34:47.429888Z","steps":["trace[144340942] 'process raft request' (duration: 184.581616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:34:47.430103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.051853476s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:34:47.430137Z","caller":"traceutil/trace.go:171","msg":"trace[451279815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"1.051921718s","start":"2026-07-09T15:34:46.378205Z","end":"2026-07-09T15:34:47.430127Z","steps":["trace[451279815] 'agreement among raft nodes before linearized reading' (duration: 1.051851385s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:34:47.430166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:34:46.378197Z","time spent":"1.05196226s","remote":"127.0.0.1:37344","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-09T15:34:47.430188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.0634853s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-09T15:34:47.430228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.063682336s","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-09T15:34:47.430278Z","caller":"traceutil/trace.go:171","msg":"trace[1818075753] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:642; }","duration":"1.063633095s","start":"2026-07-09T15:34:46.366624Z","end":"2026-07-09T15:34:47.430257Z","steps":["trace[1818075753] 'agreement among raft nodes before linearized reading' (duration: 1.063393648s)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:34:47.430293Z","caller":"traceutil/trace.go:171","msg":"trace[178460122] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:642; }","duration":"1.063785009s","start":"2026-07-09T15:34:46.366489Z","end":"2026-07-09T15:34:47.430274Z","steps":["trace[178460122] 'agreement among raft nodes before linearized reading' (duration: 1.063611143s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:34:47.430344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:34:46.366468Z","time spent":"1.06386738s","remote":"127.0.0.1:37612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T15:34:47.430342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:34:46.366598Z","time spent":"1.063729796s","remote":"127.0.0.1:37612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T15:34:51.906455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.880366ms","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-09T15:34:51.906533Z","caller":"traceutil/trace.go:171","msg":"trace[341569398] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:673; }","duration":"236.004861ms","start":"2026-07-09T15:34:51.670511Z","end":"2026-07-09T15:34:51.906516Z","steps":["trace[341569398] 'range keys from in-memory index tree' (duration: 235.67678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:34:51.906741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.391118ms","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-09T15:34:51.906815Z","caller":"traceutil/trace.go:171","msg":"trace[1924407998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:673; }","duration":"224.526862ms","start":"2026-07-09T15:34:51.68227Z","end":"2026-07-09T15:34:51.906796Z","steps":["trace[1924407998] 'range keys from in-memory index tree' (duration: 224.245984ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:34:52.842651Z","caller":"traceutil/trace.go:171","msg":"trace[687981521] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"120.117949ms","start":"2026-07-09T15:34:52.722511Z","end":"2026-07-09T15:34:52.842629Z","steps":["trace[687981521] 'process raft request' (duration: 119.580033ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:35:04.462529Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003714013727326684,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T15:35:04.624183Z","caller":"traceutil/trace.go:171","msg":"trace[1119856348] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"915.956077ms","start":"2026-07-09T15:35:03.708198Z","end":"2026-07-09T15:35:04.624154Z","steps":["trace[1119856348] 'process raft request' (duration: 915.783282ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:35:04.62436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:35:03.708172Z","time spent":"916.099061ms","remote":"127.0.0.1:37612","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-09T15:35:04.624718Z","caller":"traceutil/trace.go:171","msg":"trace[1917944823] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:833; }","duration":"662.819977ms","start":"2026-07-09T15:35:03.961861Z","end":"2026-07-09T15:35:04.624681Z","steps":["trace[1917944823] 'read index received' (duration: 662.802386ms)","trace[1917944823] 'applied index is now lower than readState.Index' (duration: 14.801µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:35:04.625035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.143407ms","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-09T15:35:04.625095Z","caller":"traceutil/trace.go:171","msg":"trace[2041204642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:805; }","duration":"663.25346ms","start":"2026-07-09T15:35:03.961826Z","end":"2026-07-09T15:35:04.625079Z","steps":["trace[2041204642] 'agreement among raft nodes before linearized reading' (duration: 663.031664ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:35:04.62516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:35:03.961807Z","time spent":"663.332242ms","remote":"127.0.0.1:37612","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-09T15:35:04.816265Z","caller":"traceutil/trace.go:171","msg":"trace[128680437] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"252.48217ms","start":"2026-07-09T15:35:04.56374Z","end":"2026-07-09T15:35:04.816222Z","steps":["trace[128680437] 'process raft request' (duration: 252.144319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:35:04.816293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.300928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-07-09T15:35:04.81629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.138958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.124\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-09T15:35:04.816372Z","caller":"traceutil/trace.go:171","msg":"trace[1341994834] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:806; }","duration":"627.435472ms","start":"2026-07-09T15:35:04.18892Z","end":"2026-07-09T15:35:04.816355Z","steps":["trace[1341994834] 'agreement among raft nodes before linearized reading' (duration: 627.193925ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:35:04.816385Z","caller":"traceutil/trace.go:171","msg":"trace[1069220037] range","detail":"{range_begin:/registry/masterleases/199.19.213.124; range_end:; response_count:1; response_revision:806; }","duration":"533.282231ms","start":"2026-07-09T15:35:04.283084Z","end":"2026-07-09T15:35:04.816367Z","steps":["trace[1069220037] 'agreement among raft nodes before linearized reading' (duration: 533.003593ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:35:04.816424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:35:04.188894Z","time spent":"627.516443ms","remote":"127.0.0.1:37612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T15:35:04.816539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:35:04.283063Z","time spent":"533.433366ms","remote":"127.0.0.1:37374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.124\" "} {"level":"warn","ts":"2026-07-09T15:35:04.816658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.623894ms","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-09T15:35:04.816706Z","caller":"traceutil/trace.go:171","msg":"trace[1560419474] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:806; }","duration":"854.711096ms","start":"2026-07-09T15:35:03.961982Z","end":"2026-07-09T15:35:04.816693Z","steps":["trace[1560419474] 'agreement among raft nodes before linearized reading' (duration: 854.549191ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:35:04.816739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:35:03.961955Z","time spent":"854.776208ms","remote":"127.0.0.1:37612","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-09T15:35:04.816056Z","caller":"traceutil/trace.go:171","msg":"trace[2050865527] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:833; }","duration":"191.211429ms","start":"2026-07-09T15:35:04.624819Z","end":"2026-07-09T15:35:04.81603Z","steps":["trace[2050865527] 'read index received' (duration: 191.098286ms)","trace[2050865527] 'applied index is now lower than readState.Index' (duration: 112.033µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:35:04.816935Z","caller":"traceutil/trace.go:171","msg":"trace[1407450116] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"187.377553ms","start":"2026-07-09T15:35:04.629541Z","end":"2026-07-09T15:35:04.816919Z","steps":["trace[1407450116] 'process raft request' (duration: 187.237679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:35:04.817152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.051061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:35:04.817202Z","caller":"traceutil/trace.go:171","msg":"trace[2056057810] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:806; }","duration":"391.093372ms","start":"2026-07-09T15:35:04.426095Z","end":"2026-07-09T15:35:04.817188Z","steps":["trace[2056057810] 'agreement among raft nodes before linearized reading' (duration: 389.971687ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:35:04.817237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:35:04.42607Z","time spent":"391.153844ms","remote":"127.0.0.1:37344","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-09T15:35:09.294782Z","caller":"traceutil/trace.go:171","msg":"trace[780663704] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"135.799275ms","start":"2026-07-09T15:35:09.158962Z","end":"2026-07-09T15:35:09.294762Z","steps":["trace[780663704] 'process raft request' (duration: 134.337562ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:35:09.29505Z","caller":"traceutil/trace.go:171","msg":"trace[86854322] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"135.210858ms","start":"2026-07-09T15:35:09.159829Z","end":"2026-07-09T15:35:09.29504Z","steps":["trace[86854322] 'process raft request' (duration: 134.562479ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:35:09.295258Z","caller":"traceutil/trace.go:171","msg":"trace[1486287848] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"133.63421ms","start":"2026-07-09T15:35:09.161615Z","end":"2026-07-09T15:35:09.295249Z","steps":["trace[1486287848] 'process raft request' (duration: 132.836317ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:35:40.698526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.188807ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-09T15:35:40.698731Z","caller":"traceutil/trace.go:171","msg":"trace[1300997124] linearizableReadLoop","detail":"{readStateIndex:1058; appliedIndex:1057; }","duration":"1.001073807s","start":"2026-07-09T15:35:39.697639Z","end":"2026-07-09T15:35:40.698712Z","steps":["trace[1300997124] 'read index received' (duration: 235.763485ms)","trace[1300997124] 'applied index is now lower than readState.Index' (duration: 765.308952ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:35:40.698686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.659832ms","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-09T15:35:40.698801Z","caller":"traceutil/trace.go:171","msg":"trace[226591240] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1021; }","duration":"560.784435ms","start":"2026-07-09T15:35:40.137999Z","end":"2026-07-09T15:35:40.698783Z","steps":["trace[226591240] 'range keys from in-memory index tree' (duration: 560.643951ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:35:40.699154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.00151577s","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-09T15:35:40.699186Z","caller":"traceutil/trace.go:171","msg":"trace[84702759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1021; }","duration":"1.001608172s","start":"2026-07-09T15:35:39.697567Z","end":"2026-07-09T15:35:40.699176Z","steps":["trace[84702759] 'agreement among raft nodes before linearized reading' (duration: 1.001423686s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:35:40.699216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:35:39.697544Z","time spent":"1.001664144s","remote":"127.0.0.1:37612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-09T15:35:40.699419Z","caller":"traceutil/trace.go:171","msg":"trace[943262085] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"308.143014ms","start":"2026-07-09T15:35:40.391245Z","end":"2026-07-09T15:35:40.699388Z","steps":["trace[943262085] 'process raft request' (duration: 307.384442ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:35:40.699599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:35:40.391222Z","time spent":"308.302519ms","remote":"127.0.0.1:37612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:35:40.758401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"986.994073ms","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-09T15:35:40.758399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.024265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T15:35:40.75846Z","caller":"traceutil/trace.go:171","msg":"trace[209093824] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1022; }","duration":"987.094525ms","start":"2026-07-09T15:35:39.77135Z","end":"2026-07-09T15:35:40.758444Z","steps":["trace[209093824] 'agreement among raft nodes before linearized reading' (duration: 928.008588ms)","trace[209093824] 'range keys from in-memory index tree' (duration: 58.930303ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:35:40.758497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:35:39.771318Z","time spent":"987.171088ms","remote":"127.0.0.1:37612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-09T15:35:40.75848Z","caller":"traceutil/trace.go:171","msg":"trace[1519072793] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1022; }","duration":"232.152988ms","start":"2026-07-09T15:35:40.526305Z","end":"2026-07-09T15:35:40.758458Z","steps":["trace[1519072793] 'agreement among raft nodes before linearized reading' (duration: 173.092801ms)","trace[1519072793] 'count revisions from in-memory index tree' (duration: 58.945703ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:35:40.759116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.309166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:35:40.759182Z","caller":"traceutil/trace.go:171","msg":"trace[1205196657] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1022; }","duration":"380.375338ms","start":"2026-07-09T15:35:40.378792Z","end":"2026-07-09T15:35:40.759167Z","steps":["trace[1205196657] 'agreement among raft nodes before linearized reading' (duration: 320.622631ms)","trace[1205196657] 'range keys from in-memory index tree' (duration: 58.749738ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:35:40.759216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:35:40.378723Z","time spent":"380.484772ms","remote":"127.0.0.1:37344","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-09T15:35:41.75236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.314289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-09T15:35:41.752413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.582491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-09T15:35:41.752442Z","caller":"traceutil/trace.go:171","msg":"trace[1118562951] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1024; }","duration":"343.444032ms","start":"2026-07-09T15:35:41.40898Z","end":"2026-07-09T15:35:41.752424Z","steps":["trace[1118562951] 'count revisions from in-memory index tree' (duration: 343.197876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:35:41.75246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.501218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T15:35:41.752485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:35:41.408961Z","time spent":"343.513914ms","remote":"127.0.0.1:37500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":11,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-07-09T15:35:41.752491Z","caller":"traceutil/trace.go:171","msg":"trace[1713037290] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1024; }","duration":"166.566549ms","start":"2026-07-09T15:35:41.585916Z","end":"2026-07-09T15:35:41.752483Z","steps":["trace[1713037290] 'count revisions from in-memory index tree' (duration: 166.419995ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:35:41.752629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.748785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-09T15:35:41.752623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.196489ms","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-09T15:35:41.75314Z","caller":"traceutil/trace.go:171","msg":"trace[1570480273] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1024; }","duration":"677.743066ms","start":"2026-07-09T15:35:41.075376Z","end":"2026-07-09T15:35:41.753119Z","steps":["trace[1570480273] 'range keys from in-memory index tree' (duration: 677.048126ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:35:41.752649Z","caller":"traceutil/trace.go:171","msg":"trace[884904177] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1024; }","duration":"679.771305ms","start":"2026-07-09T15:35:41.072871Z","end":"2026-07-09T15:35:41.752643Z","steps":["trace[884904177] 'range keys from in-memory index tree' (duration: 679.63067ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:35:41.753211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:35:41.075365Z","time spent":"677.829378ms","remote":"127.0.0.1:37612","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-09T15:35:41.752453Z","caller":"traceutil/trace.go:171","msg":"trace[804541811] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1024; }","duration":"678.663803ms","start":"2026-07-09T15:35:41.073779Z","end":"2026-07-09T15:35:41.752443Z","steps":["trace[804541811] 'range keys from in-memory index tree' (duration: 678.429997ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:35:41.753277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:35:41.072848Z","time spent":"680.401055ms","remote":"127.0.0.1:37612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T15:35:41.753355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:35:41.073757Z","time spent":"679.548819ms","remote":"127.0.0.1:37612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-09T15:35:42.255819Z","caller":"traceutil/trace.go:171","msg":"trace[465405539] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"499.716599ms","start":"2026-07-09T15:35:41.756077Z","end":"2026-07-09T15:35:42.255794Z","steps":["trace[465405539] 'process raft request' (duration: 499.563444ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:35:42.255964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:35:41.756056Z","time spent":"499.838002ms","remote":"127.0.0.1:37612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:35:42.700294Z","caller":"traceutil/trace.go:171","msg":"trace[782068580] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"943.77929ms","start":"2026-07-09T15:35:41.756489Z","end":"2026-07-09T15:35:42.700268Z","steps":["trace[782068580] 'process raft request' (duration: 943.595484ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:35:42.700465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:35:41.756466Z","time spent":"943.901885ms","remote":"127.0.0.1:37612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:35:42.700437Z","caller":"traceutil/trace.go:171","msg":"trace[919443079] linearizableReadLoop","detail":"{readStateIndex:1064; appliedIndex:1062; }","duration":"336.404105ms","start":"2026-07-09T15:35:42.364006Z","end":"2026-07-09T15:35:42.70041Z","steps":["trace[919443079] 'read index received' (duration: 335.995544ms)","trace[919443079] 'applied index is now lower than readState.Index' (duration: 406.871µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:35:42.700497Z","caller":"traceutil/trace.go:171","msg":"trace[1533364917] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"943.541714ms","start":"2026-07-09T15:35:41.756916Z","end":"2026-07-09T15:35:42.700458Z","steps":["trace[1533364917] 'process raft request' (duration: 943.314407ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:35:42.700597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.56284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-09T15:35:42.70063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:35:41.756904Z","time spent":"943.658728ms","remote":"127.0.0.1:37612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:35:42.700634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.822114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:35:42.700652Z","caller":"traceutil/trace.go:171","msg":"trace[62337066] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1027; }","duration":"336.677544ms","start":"2026-07-09T15:35:42.363962Z","end":"2026-07-09T15:35:42.70064Z","steps":["trace[62337066] 'agreement among raft nodes before linearized reading' (duration: 336.53244ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:35:42.700673Z","caller":"traceutil/trace.go:171","msg":"trace[2122076502] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1027; }","duration":"323.872717ms","start":"2026-07-09T15:35:42.376789Z","end":"2026-07-09T15:35:42.700661Z","steps":["trace[2122076502] 'agreement among raft nodes before linearized reading' (duration: 323.816665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:35:42.700703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:35:42.376781Z","time spent":"323.916408ms","remote":"127.0.0.1:37344","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-09T15:35:42.700699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:35:42.36391Z","time spent":"336.777936ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-07-09T15:36:02.816018Z","caller":"traceutil/trace.go:171","msg":"trace[736157685] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"407.418804ms","start":"2026-07-09T15:36:02.408576Z","end":"2026-07-09T15:36:02.815995Z","steps":["trace[736157685] 'process raft request' (duration: 407.23922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:02.816166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:02.408559Z","time spent":"407.531057ms","remote":"127.0.0.1:37612","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-09T15:36:03.141695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.416107ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:36:03.141793Z","caller":"traceutil/trace.go:171","msg":"trace[1329050728] linearizableReadLoop","detail":"{readStateIndex:1133; appliedIndex:1132; }","duration":"301.320563ms","start":"2026-07-09T15:36:02.840456Z","end":"2026-07-09T15:36:03.141776Z","steps":["trace[1329050728] 'read index received' (duration: 35.658908ms)","trace[1329050728] 'applied index is now lower than readState.Index' (duration: 265.660035ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:36:03.141888Z","caller":"traceutil/trace.go:171","msg":"trace[1052481127] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"338.349893ms","start":"2026-07-09T15:36:02.803482Z","end":"2026-07-09T15:36:03.141832Z","steps":["trace[1052481127] 'process raft request' (duration: 72.689919ms)","trace[1052481127] 'compare' (duration: 265.193529ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:36:03.142004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.54532ms","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-09T15:36:03.142058Z","caller":"traceutil/trace.go:171","msg":"trace[46315524] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1092; }","duration":"301.628202ms","start":"2026-07-09T15:36:02.840417Z","end":"2026-07-09T15:36:03.142045Z","steps":["trace[46315524] 'agreement among raft nodes before linearized reading' (duration: 301.455707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:03.142053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:02.803461Z","time spent":"338.504647ms","remote":"127.0.0.1:37612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:36:03.142115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:02.840381Z","time spent":"301.717904ms","remote":"127.0.0.1:37612","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-09T15:36:03.14224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.529112ms","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-09T15:36:03.142593Z","caller":"traceutil/trace.go:171","msg":"trace[1255972004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1092; }","duration":"296.022998ms","start":"2026-07-09T15:36:02.846554Z","end":"2026-07-09T15:36:03.142577Z","steps":["trace[1255972004] 'agreement among raft nodes before linearized reading' (duration: 295.518963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:03.142412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.543172ms","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-09T15:36:03.142743Z","caller":"traceutil/trace.go:171","msg":"trace[721616612] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1092; }","duration":"297.902922ms","start":"2026-07-09T15:36:02.844822Z","end":"2026-07-09T15:36:03.142725Z","steps":["trace[721616612] 'agreement among raft nodes before linearized reading' (duration: 297.202752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:03.142667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.882441ms","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-09T15:36:03.142899Z","caller":"traceutil/trace.go:171","msg":"trace[1122561773] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1092; }","duration":"282.175241ms","start":"2026-07-09T15:36:02.860716Z","end":"2026-07-09T15:36:03.142891Z","steps":["trace[1122561773] 'agreement among raft nodes before linearized reading' (duration: 281.864622ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:36:04.453916Z","caller":"traceutil/trace.go:171","msg":"trace[790201120] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"150.876328ms","start":"2026-07-09T15:36:04.303006Z","end":"2026-07-09T15:36:04.453882Z","steps":["trace[790201120] 'process raft request' (duration: 115.732784ms)","trace[790201120] 'compare' (duration: 35.00022ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:36:05.296609Z","caller":"traceutil/trace.go:171","msg":"trace[1469172352] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1141; }","duration":"105.045261ms","start":"2026-07-09T15:36:05.191539Z","end":"2026-07-09T15:36:05.296585Z","steps":["trace[1469172352] 'read index received' (duration: 60.105388ms)","trace[1469172352] 'applied index is now lower than readState.Index' (duration: 44.938573ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:36:05.296686Z","caller":"traceutil/trace.go:171","msg":"trace[2138053214] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"109.287795ms","start":"2026-07-09T15:36:05.187357Z","end":"2026-07-09T15:36:05.296645Z","steps":["trace[2138053214] 'process raft request' (duration: 64.278661ms)","trace[2138053214] 'compare' (duration: 44.822309ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:36:05.296832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.270587ms","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-09T15:36:05.296931Z","caller":"traceutil/trace.go:171","msg":"trace[273558703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1100; }","duration":"105.354289ms","start":"2026-07-09T15:36:05.191509Z","end":"2026-07-09T15:36:05.296863Z","steps":["trace[273558703] 'agreement among raft nodes before linearized reading' (duration: 105.206975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:05.297157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.510084ms","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-09T15:36:05.297251Z","caller":"traceutil/trace.go:171","msg":"trace[1640720691] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1100; }","duration":"105.653637ms","start":"2026-07-09T15:36:05.191574Z","end":"2026-07-09T15:36:05.297228Z","steps":["trace[1640720691] 'agreement among raft nodes before linearized reading' (duration: 105.280546ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:05.297204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.610207ms","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-09T15:36:05.297498Z","caller":"traceutil/trace.go:171","msg":"trace[1162322328] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1100; }","duration":"105.948886ms","start":"2026-07-09T15:36:05.191525Z","end":"2026-07-09T15:36:05.297474Z","steps":["trace[1162322328] 'agreement among raft nodes before linearized reading' (duration: 105.306597ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:36:05.948129Z","caller":"traceutil/trace.go:171","msg":"trace[175243011] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"204.742462ms","start":"2026-07-09T15:36:05.743361Z","end":"2026-07-09T15:36:05.948104Z","steps":["trace[175243011] 'process raft request' (duration: 204.531006ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:36:07.342245Z","caller":"traceutil/trace.go:171","msg":"trace[331888535] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"494.860826ms","start":"2026-07-09T15:36:06.847359Z","end":"2026-07-09T15:36:07.34222Z","steps":["trace[331888535] 'process raft request' (duration: 494.712142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:07.342466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:06.84734Z","time spent":"495.049692ms","remote":"127.0.0.1:37612","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-09T15:36:08.177646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.004253ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:36:08.177739Z","caller":"traceutil/trace.go:171","msg":"trace[337451545] linearizableReadLoop","detail":"{readStateIndex:1148; appliedIndex:1146; }","duration":"971.724223ms","start":"2026-07-09T15:36:07.205997Z","end":"2026-07-09T15:36:08.177722Z","steps":["trace[337451545] 'read index received' (duration: 136.029382ms)","trace[337451545] 'applied index is now lower than readState.Index' (duration: 835.693431ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:36:08.178047Z","caller":"traceutil/trace.go:171","msg":"trace[1268044662] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"990.358581ms","start":"2026-07-09T15:36:07.187674Z","end":"2026-07-09T15:36:08.178033Z","steps":["trace[1268044662] 'process raft request' (duration: 346.862654ms)","trace[1268044662] 'compare' (duration: 642.562521ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:36:08.178148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:07.187649Z","time spent":"990.430793ms","remote":"127.0.0.1:37612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:36:08.178148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.917769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-09T15:36:08.178113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.093564ms","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":"info","ts":"2026-07-09T15:36:08.178196Z","caller":"traceutil/trace.go:171","msg":"trace[939201379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1106; }","duration":"798.989941ms","start":"2026-07-09T15:36:07.37919Z","end":"2026-07-09T15:36:08.17818Z","steps":["trace[939201379] 'agreement among raft nodes before linearized reading' (duration: 798.860547ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:36:08.178203Z","caller":"traceutil/trace.go:171","msg":"trace[990329876] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1106; }","duration":"972.241739ms","start":"2026-07-09T15:36:07.205948Z","end":"2026-07-09T15:36:08.178189Z","steps":["trace[990329876] 'agreement among raft nodes before linearized reading' (duration: 972.059683ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:08.178196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.839351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-09T15:36:08.178228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:07.379175Z","time spent":"799.045343ms","remote":"127.0.0.1:37612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T15:36:08.178211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.557711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-09T15:36:08.17824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:07.205931Z","time spent":"972.299469ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-07-09T15:36:08.178251Z","caller":"traceutil/trace.go:171","msg":"trace[1390152070] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1106; }","duration":"876.924323ms","start":"2026-07-09T15:36:07.301313Z","end":"2026-07-09T15:36:08.178237Z","steps":["trace[1390152070] 'agreement among raft nodes before linearized reading' (duration: 876.717367ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:08.178245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.343584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-09T15:36:08.178275Z","caller":"traceutil/trace.go:171","msg":"trace[133350703] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1106; }","duration":"863.675904ms","start":"2026-07-09T15:36:07.314581Z","end":"2026-07-09T15:36:08.178257Z","steps":["trace[133350703] 'agreement among raft nodes before linearized reading' (duration: 863.490279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:08.178285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:07.301297Z","time spent":"876.980966ms","remote":"127.0.0.1:37612","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-09T15:36:08.178344Z","caller":"traceutil/trace.go:171","msg":"trace[582619884] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1106; }","duration":"797.462757ms","start":"2026-07-09T15:36:07.380851Z","end":"2026-07-09T15:36:08.178314Z","steps":["trace[582619884] 'agreement among raft nodes before linearized reading' (duration: 797.21602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:08.178344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:07.314562Z","time spent":"863.755587ms","remote":"127.0.0.1:37612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T15:36:08.178391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:07.380821Z","time spent":"797.56088ms","remote":"127.0.0.1:37612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T15:36:08.879094Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003714013727327390,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T15:36:09.129279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.508ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:36:09.129412Z","caller":"traceutil/trace.go:171","msg":"trace[1898138488] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"947.335975ms","start":"2026-07-09T15:36:08.182051Z","end":"2026-07-09T15:36:09.129387Z","steps":["trace[1898138488] 'process raft request' (duration: 187.616559ms)","trace[1898138488] 'compare' (duration: 759.347516ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:36:09.129499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:08.182034Z","time spent":"947.421598ms","remote":"127.0.0.1:37612","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-09T15:36:09.135157Z","caller":"traceutil/trace.go:171","msg":"trace[1224471200] linearizableReadLoop","detail":"{readStateIndex:1152; appliedIndex:1149; }","duration":"756.083639ms","start":"2026-07-09T15:36:08.379056Z","end":"2026-07-09T15:36:09.135139Z","steps":["trace[1224471200] 'read index received' (duration: 755.874094ms)","trace[1224471200] 'applied index is now lower than readState.Index' (duration: 208.335µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:36:09.135375Z","caller":"traceutil/trace.go:171","msg":"trace[1521259821] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"952.884148ms","start":"2026-07-09T15:36:08.182479Z","end":"2026-07-09T15:36:09.135363Z","steps":["trace[1521259821] 'process raft request' (duration: 952.490567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:09.135446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:08.182452Z","time spent":"952.957561ms","remote":"127.0.0.1:37612","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-09T15:36:09.135562Z","caller":"traceutil/trace.go:171","msg":"trace[2110831612] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"952.666682ms","start":"2026-07-09T15:36:08.18288Z","end":"2026-07-09T15:36:09.135547Z","steps":["trace[2110831612] 'process raft request' (duration: 952.187238ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:36:09.135615Z","caller":"traceutil/trace.go:171","msg":"trace[1573412547] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"952.628161ms","start":"2026-07-09T15:36:08.182969Z","end":"2026-07-09T15:36:09.135597Z","steps":["trace[1573412547] 'process raft request' (duration: 952.134796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:09.135645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:08.182872Z","time spent":"952.739524ms","remote":"127.0.0.1:37612","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-09T15:36:09.135559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.499331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T15:36:09.135725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:08.182952Z","time spent":"952.713344ms","remote":"127.0.0.1:37612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:36:09.135727Z","caller":"traceutil/trace.go:171","msg":"trace[938533676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1110; }","duration":"756.678257ms","start":"2026-07-09T15:36:08.379016Z","end":"2026-07-09T15:36:09.135694Z","steps":["trace[938533676] 'agreement among raft nodes before linearized reading' (duration: 756.492161ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:09.135804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:08.379Z","time spent":"756.77701ms","remote":"127.0.0.1:37344","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-09T15:36:09.136525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.952492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T15:36:09.136705Z","caller":"traceutil/trace.go:171","msg":"trace[284982896] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1110; }","duration":"285.196079ms","start":"2026-07-09T15:36:08.851488Z","end":"2026-07-09T15:36:09.136684Z","steps":["trace[284982896] 'agreement among raft nodes before linearized reading' (duration: 284.335244ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:36:21.156036Z","caller":"traceutil/trace.go:171","msg":"trace[141711947] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"166.933025ms","start":"2026-07-09T15:36:20.989081Z","end":"2026-07-09T15:36:21.156014Z","steps":["trace[141711947] 'process raft request' (duration: 166.788671ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:36:23.862796Z","caller":"traceutil/trace.go:171","msg":"trace[891010966] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"559.468983ms","start":"2026-07-09T15:36:23.3033Z","end":"2026-07-09T15:36:23.862769Z","steps":["trace[891010966] 'process raft request' (duration: 558.994948ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:23.862955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:23.303292Z","time spent":"559.581496ms","remote":"127.0.0.1:37612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:36:23.863029Z","caller":"traceutil/trace.go:171","msg":"trace[731283341] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"559.0123ms","start":"2026-07-09T15:36:23.303994Z","end":"2026-07-09T15:36:23.863006Z","steps":["trace[731283341] 'process raft request' (duration: 558.730811ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:36:23.863149Z","caller":"traceutil/trace.go:171","msg":"trace[781921014] linearizableReadLoop","detail":"{readStateIndex:1254; appliedIndex:1251; }","duration":"484.619032ms","start":"2026-07-09T15:36:23.378519Z","end":"2026-07-09T15:36:23.863138Z","steps":["trace[781921014] 'read index received' (duration: 483.733396ms)","trace[781921014] 'applied index is now lower than readState.Index' (duration: 884.826µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:36:23.863161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:23.303987Z","time spent":"559.099801ms","remote":"127.0.0.1:37612","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-09T15:36:23.863289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.778066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:36:23.863318Z","caller":"traceutil/trace.go:171","msg":"trace[766537044] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1207; }","duration":"484.841899ms","start":"2026-07-09T15:36:23.378466Z","end":"2026-07-09T15:36:23.863308Z","steps":["trace[766537044] 'agreement among raft nodes before linearized reading' (duration: 484.799307ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:23.863345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:23.378446Z","time spent":"484.891192ms","remote":"127.0.0.1:37344","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-09T15:36:23.863418Z","caller":"traceutil/trace.go:171","msg":"trace[1286590782] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"558.605477ms","start":"2026-07-09T15:36:23.30479Z","end":"2026-07-09T15:36:23.863395Z","steps":["trace[1286590782] 'process raft request' (duration: 558.149183ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:23.863501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:23.304784Z","time spent":"558.67563ms","remote":"127.0.0.1:37612","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-09T15:36:23.864127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.521738ms","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-09T15:36:23.864176Z","caller":"traceutil/trace.go:171","msg":"trace[1066023152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1207; }","duration":"441.575119ms","start":"2026-07-09T15:36:23.422589Z","end":"2026-07-09T15:36:23.864164Z","steps":["trace[1066023152] 'agreement among raft nodes before linearized reading' (duration: 440.702654ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:23.864211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:23.42255Z","time spent":"441.653091ms","remote":"127.0.0.1:37612","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-09T15:36:24.787525Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003714013727327617,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T15:36:25.287659Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003714013727327617,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T15:36:25.788309Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003714013727327617,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T15:36:26.288887Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003714013727327617,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T15:36:26.423507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000158718s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-09T15:36:26.423584Z","caller":"traceutil/trace.go:171","msg":"trace[1820803515] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000275182s","start":"2026-07-09T15:36:24.423288Z","end":"2026-07-09T15:36:26.423564Z","steps":["trace[1820803515] 'agreement among raft nodes before linearized reading' (duration: 2.000172919s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:26.423645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:24.423275Z","time spent":"2.000357073s","remote":"127.0.0.1:37344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/09 15:36:26 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-09T15:36:26.789788Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003714013727327617,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T15:36:27.290203Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003714013727327617,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T15:36:27.378175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000315411s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-09T15:36:27.378303Z","caller":"traceutil/trace.go:171","msg":"trace[1748573244] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000483098s","start":"2026-07-09T15:36:25.377796Z","end":"2026-07-09T15:36:27.37828Z","steps":["trace[1748573244] 'agreement among raft nodes before linearized reading' (duration: 2.000339443s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:27.378391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:25.377782Z","time spent":"2.000591701s","remote":"127.0.0.1:37346","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-09T15:36:27.567346Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.340881583s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T15:36:27.567806Z","caller":"traceutil/trace.go:171","msg":"trace[1621289107] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"3.341389518s","start":"2026-07-09T15:36:24.226393Z","end":"2026-07-09T15:36:27.567783Z","steps":["trace[1621289107] 'process raft request' (duration: 3.341207683s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:27.567984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:24.226378Z","time spent":"3.341510672s","remote":"127.0.0.1:37612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:36:27.641241Z","caller":"traceutil/trace.go:171","msg":"trace[1442538516] linearizableReadLoop","detail":"{readStateIndex:1258; appliedIndex:1255; }","duration":"3.353985863s","start":"2026-07-09T15:36:24.287238Z","end":"2026-07-09T15:36:27.641224Z","steps":["trace[1442538516] 'read index received' (duration: 3.280454253s)","trace[1442538516] 'applied index is now lower than readState.Index' (duration: 73.53098ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:36:27.641319Z","caller":"traceutil/trace.go:171","msg":"trace[1546108726] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"1.215178635s","start":"2026-07-09T15:36:26.426109Z","end":"2026-07-09T15:36:27.641288Z","steps":["trace[1546108726] 'process raft request' (duration: 1.210164355s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:27.641435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.354182619s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.124\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-09T15:36:27.641479Z","caller":"traceutil/trace.go:171","msg":"trace[560510812] range","detail":"{range_begin:/registry/masterleases/199.19.213.124; range_end:; response_count:1; response_revision:1211; }","duration":"3.354270092s","start":"2026-07-09T15:36:24.287203Z","end":"2026-07-09T15:36:27.641473Z","steps":["trace[560510812] 'agreement among raft nodes before linearized reading' (duration: 3.354116846s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:27.641465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:26.426086Z","time spent":"1.215306348s","remote":"127.0.0.1:37416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T15:36:27.64152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:24.287188Z","time spent":"3.354309854s","remote":"127.0.0.1:37374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.124\" "} {"level":"info","ts":"2026-07-09T15:36:27.641715Z","caller":"traceutil/trace.go:171","msg":"trace[2077981670] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"928.237123ms","start":"2026-07-09T15:36:26.713115Z","end":"2026-07-09T15:36:27.641352Z","steps":["trace[2077981670] 'process raft request' (duration: 928.056738ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:27.641765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.307935469s","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-09T15:36:27.641823Z","caller":"traceutil/trace.go:171","msg":"trace[42255438] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1211; }","duration":"2.308032042s","start":"2026-07-09T15:36:25.333774Z","end":"2026-07-09T15:36:27.641806Z","steps":["trace[42255438] 'agreement among raft nodes before linearized reading' (duration: 2.307857107s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:27.641876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.773297187s","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-09T15:36:27.641925Z","caller":"traceutil/trace.go:171","msg":"trace[1064934770] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1211; }","duration":"1.77336976s","start":"2026-07-09T15:36:25.868545Z","end":"2026-07-09T15:36:27.641914Z","steps":["trace[1064934770] 'agreement among raft nodes before linearized reading' (duration: 1.773255236s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:27.641958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:25.868533Z","time spent":"1.77341764s","remote":"127.0.0.1:37612","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-09T15:36:27.64199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.774520623s","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-09T15:36:27.642037Z","caller":"traceutil/trace.go:171","msg":"trace[439373773] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1211; }","duration":"1.774604226s","start":"2026-07-09T15:36:25.86742Z","end":"2026-07-09T15:36:27.642024Z","steps":["trace[439373773] 'agreement among raft nodes before linearized reading' (duration: 1.774488332s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:27.642083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:25.867392Z","time spent":"1.774678208s","remote":"127.0.0.1:37612","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-09T15:36:27.642115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.507296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:36:27.642149Z","caller":"traceutil/trace.go:171","msg":"trace[1034667606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1211; }","duration":"255.577276ms","start":"2026-07-09T15:36:27.386563Z","end":"2026-07-09T15:36:27.64214Z","steps":["trace[1034667606] 'agreement among raft nodes before linearized reading' (duration: 255.529624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:27.642151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.774808382s","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-09T15:36:27.642242Z","caller":"traceutil/trace.go:171","msg":"trace[1806516351] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1211; }","duration":"1.774934355s","start":"2026-07-09T15:36:25.867296Z","end":"2026-07-09T15:36:27.64223Z","steps":["trace[1806516351] 'agreement among raft nodes before linearized reading' (duration: 1.774727619s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:27.642329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:25.867279Z","time spent":"1.775015998s","remote":"127.0.0.1:37612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T15:36:27.642341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.77072466s","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-09T15:36:27.642373Z","caller":"traceutil/trace.go:171","msg":"trace[259470921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1211; }","duration":"1.770777392s","start":"2026-07-09T15:36:25.871587Z","end":"2026-07-09T15:36:27.642364Z","steps":["trace[259470921] 'agreement among raft nodes before linearized reading' (duration: 1.770672629s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:27.642403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:25.871573Z","time spent":"1.770823974s","remote":"127.0.0.1:37612","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-09T15:36:27.641878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:25.333755Z","time spent":"2.308108896s","remote":"127.0.0.1:37612","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-09T15:36:27.642592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:26.713093Z","time spent":"928.716887ms","remote":"127.0.0.1:37612","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-09T15:36:39.725516Z","caller":"traceutil/trace.go:171","msg":"trace[517868198] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"121.535071ms","start":"2026-07-09T15:36:39.60396Z","end":"2026-07-09T15:36:39.725495Z","steps":["trace[517868198] 'process raft request' (duration: 121.419498ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:36:42.234471Z","caller":"traceutil/trace.go:171","msg":"trace[1332393445] linearizableReadLoop","detail":"{readStateIndex:1309; appliedIndex:1308; }","duration":"496.761334ms","start":"2026-07-09T15:36:41.737688Z","end":"2026-07-09T15:36:42.234449Z","steps":["trace[1332393445] 'read index received' (duration: 496.566719ms)","trace[1332393445] 'applied index is now lower than readState.Index' (duration: 193.835µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:36:42.234614Z","caller":"traceutil/trace.go:171","msg":"trace[1765330089] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"503.65076ms","start":"2026-07-09T15:36:41.730928Z","end":"2026-07-09T15:36:42.234579Z","steps":["trace[1765330089] 'process raft request' (duration: 503.376672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:42.23469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.554918ms","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-09T15:36:42.234726Z","caller":"traceutil/trace.go:171","msg":"trace[680415905] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1259; }","duration":"494.643191ms","start":"2026-07-09T15:36:41.740074Z","end":"2026-07-09T15:36:42.234717Z","steps":["trace[680415905] 'agreement among raft nodes before linearized reading' (duration: 494.537248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:42.234755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:41.740052Z","time spent":"494.698153ms","remote":"127.0.0.1:37612","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-09T15:36:42.234769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.59231ms","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-09T15:36:42.234765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:41.730898Z","time spent":"503.787543ms","remote":"127.0.0.1:37612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:36:42.23469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.98587ms","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-09T15:36:42.234799Z","caller":"traceutil/trace.go:171","msg":"trace[937790544] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1259; }","duration":"492.656122ms","start":"2026-07-09T15:36:41.742134Z","end":"2026-07-09T15:36:42.23479Z","steps":["trace[937790544] 'agreement among raft nodes before linearized reading' (duration: 492.58656ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:42.234828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:41.742115Z","time spent":"492.706643ms","remote":"127.0.0.1:37612","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-09T15:36:42.23484Z","caller":"traceutil/trace.go:171","msg":"trace[1671051900] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1259; }","duration":"497.153227ms","start":"2026-07-09T15:36:41.737659Z","end":"2026-07-09T15:36:42.234812Z","steps":["trace[1671051900] 'agreement among raft nodes before linearized reading' (duration: 496.909549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:42.234926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:41.737644Z","time spent":"497.253839ms","remote":"127.0.0.1:37612","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-09T15:36:42.234954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.261521ms","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-09T15:36:42.235004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.330783ms","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-09T15:36:42.235036Z","caller":"traceutil/trace.go:171","msg":"trace[1672527585] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1259; }","duration":"489.475067ms","start":"2026-07-09T15:36:41.745541Z","end":"2026-07-09T15:36:42.235016Z","steps":["trace[1672527585] 'agreement among raft nodes before linearized reading' (duration: 489.201809ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:36:42.235073Z","caller":"traceutil/trace.go:171","msg":"trace[1009293807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1259; }","duration":"489.425546ms","start":"2026-07-09T15:36:41.745633Z","end":"2026-07-09T15:36:42.235058Z","steps":["trace[1009293807] 'agreement among raft nodes before linearized reading' (duration: 489.23122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:42.235087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:41.745527Z","time spent":"489.550469ms","remote":"127.0.0.1:37612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T15:36:42.235111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:41.74562Z","time spent":"489.473337ms","remote":"127.0.0.1:37612","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-09T15:36:42.396098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.637689ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:36:42.396401Z","caller":"traceutil/trace.go:171","msg":"trace[249026790] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"156.411392ms","start":"2026-07-09T15:36:42.239953Z","end":"2026-07-09T15:36:42.396365Z","steps":["trace[249026790] 'process raft request' (duration: 156.237887ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:36:42.396473Z","caller":"traceutil/trace.go:171","msg":"trace[930113981] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"156.465683ms","start":"2026-07-09T15:36:42.239999Z","end":"2026-07-09T15:36:42.396464Z","steps":["trace[930113981] 'process raft request' (duration: 156.259727ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:36:42.396581Z","caller":"traceutil/trace.go:171","msg":"trace[993004978] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"156.483693ms","start":"2026-07-09T15:36:42.240079Z","end":"2026-07-09T15:36:42.396563Z","steps":["trace[993004978] 'process raft request' (duration: 156.241065ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:36:42.396703Z","caller":"traceutil/trace.go:171","msg":"trace[1713184640] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"156.877815ms","start":"2026-07-09T15:36:42.239811Z","end":"2026-07-09T15:36:42.396689Z","steps":["trace[1713184640] 'process raft request' (duration: 54.529884ms)","trace[1713184640] 'compare' (duration: 101.541076ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:36:42.396409Z","caller":"traceutil/trace.go:171","msg":"trace[1132154770] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"156.306778ms","start":"2026-07-09T15:36:42.24007Z","end":"2026-07-09T15:36:42.396377Z","steps":["trace[1132154770] 'process raft request' (duration: 156.220645ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:43.220339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.249582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:36:43.220412Z","caller":"traceutil/trace.go:171","msg":"trace[1839880011] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1264; }","duration":"532.332685ms","start":"2026-07-09T15:36:42.688061Z","end":"2026-07-09T15:36:43.220394Z","steps":["trace[1839880011] 'count revisions from in-memory index tree' (duration: 532.179919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:43.220447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:42.688008Z","time spent":"532.429138ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-07-09T15:36:44.78807Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003714013727327747,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T15:36:44.924649Z","caller":"traceutil/trace.go:171","msg":"trace[1558950235] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1316; }","duration":"636.84255ms","start":"2026-07-09T15:36:44.287777Z","end":"2026-07-09T15:36:44.924619Z","steps":["trace[1558950235] 'read index received' (duration: 636.714156ms)","trace[1558950235] 'applied index is now lower than readState.Index' (duration: 127.344µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:36:44.925097Z","caller":"traceutil/trace.go:171","msg":"trace[2121745557] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"684.660594ms","start":"2026-07-09T15:36:44.240415Z","end":"2026-07-09T15:36:44.925075Z","steps":["trace[2121745557] 'process raft request' (duration: 684.031205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:44.925238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:44.24039Z","time spent":"684.751377ms","remote":"127.0.0.1:37612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:36:44.925467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.685155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.124\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-09T15:36:44.925503Z","caller":"traceutil/trace.go:171","msg":"trace[1441342598] range","detail":"{range_begin:/registry/masterleases/199.19.213.124; range_end:; response_count:1; response_revision:1266; }","duration":"637.758077ms","start":"2026-07-09T15:36:44.287735Z","end":"2026-07-09T15:36:44.925493Z","steps":["trace[1441342598] 'agreement among raft nodes before linearized reading' (duration: 637.622733ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:44.925532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:44.287717Z","time spent":"637.808727ms","remote":"127.0.0.1:37374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.124\" "} {"level":"warn","ts":"2026-07-09T15:36:44.925871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.656981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:36:44.925919Z","caller":"traceutil/trace.go:171","msg":"trace[932536406] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1266; }","duration":"547.747523ms","start":"2026-07-09T15:36:44.378159Z","end":"2026-07-09T15:36:44.925906Z","steps":["trace[932536406] 'agreement among raft nodes before linearized reading' (duration: 547.66543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:44.925949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:44.378137Z","time spent":"547.805136ms","remote":"127.0.0.1:37344","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-09T15:36:44.925976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.197681ms","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-09T15:36:44.926045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.203802ms","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-09T15:36:44.926085Z","caller":"traceutil/trace.go:171","msg":"trace[1983388499] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1266; }","duration":"524.247363ms","start":"2026-07-09T15:36:44.401825Z","end":"2026-07-09T15:36:44.926072Z","steps":["trace[1983388499] 'agreement among raft nodes before linearized reading' (duration: 524.188892ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:44.926128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:44.401819Z","time spent":"524.304845ms","remote":"127.0.0.1:37612","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-09T15:36:44.926173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.308625ms","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-09T15:36:44.926272Z","caller":"traceutil/trace.go:171","msg":"trace[1560207932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1266; }","duration":"525.434279ms","start":"2026-07-09T15:36:44.400822Z","end":"2026-07-09T15:36:44.926256Z","steps":["trace[1560207932] 'agreement among raft nodes before linearized reading' (duration: 525.248193ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:36:44.926037Z","caller":"traceutil/trace.go:171","msg":"trace[223842419] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1266; }","duration":"525.282435ms","start":"2026-07-09T15:36:44.400738Z","end":"2026-07-09T15:36:44.926021Z","steps":["trace[223842419] 'agreement among raft nodes before linearized reading' (duration: 525.138771ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:44.926316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:44.400731Z","time spent":"525.579454ms","remote":"127.0.0.1:37612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T15:36:44.926319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:44.400806Z","time spent":"525.49803ms","remote":"127.0.0.1:37612","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-09T15:36:44.925979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.210232ms","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-09T15:36:44.926391Z","caller":"traceutil/trace.go:171","msg":"trace[521331631] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1266; }","duration":"524.676747ms","start":"2026-07-09T15:36:44.401708Z","end":"2026-07-09T15:36:44.926385Z","steps":["trace[521331631] 'agreement among raft nodes before linearized reading' (duration: 524.12181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:44.926406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:44.401694Z","time spent":"524.706607ms","remote":"127.0.0.1:37612","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-09T15:36:44.926445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.832171ms","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-09T15:36:44.926489Z","caller":"traceutil/trace.go:171","msg":"trace[1484344249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1266; }","duration":"525.916453ms","start":"2026-07-09T15:36:44.400559Z","end":"2026-07-09T15:36:44.926476Z","steps":["trace[1484344249] 'agreement among raft nodes before linearized reading' (duration: 525.805059ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:44.926519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:44.400537Z","time spent":"525.974465ms","remote":"127.0.0.1:37612","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-09T15:36:45.438281Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003714013727327752,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T15:36:46.229304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.71739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:36:46.229373Z","caller":"traceutil/trace.go:171","msg":"trace[391312366] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"1.298058193s","start":"2026-07-09T15:36:44.931303Z","end":"2026-07-09T15:36:46.229361Z","steps":["trace[391312366] 'process raft request' (duration: 684.2314ms)","trace[391312366] 'compare' (duration: 613.134973ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:36:46.229404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:44.931276Z","time spent":"1.298113965s","remote":"127.0.0.1:37612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:36:46.393844Z","caller":"traceutil/trace.go:171","msg":"trace[191640365] linearizableReadLoop","detail":"{readStateIndex:1323; appliedIndex:1317; }","duration":"1.456172615s","start":"2026-07-09T15:36:44.937657Z","end":"2026-07-09T15:36:46.39383Z","steps":["trace[191640365] 'read index received' (duration: 677.881211ms)","trace[191640365] 'applied index is now lower than readState.Index' (duration: 778.290854ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:36:46.393936Z","caller":"traceutil/trace.go:171","msg":"trace[1710521546] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"1.461995078s","start":"2026-07-09T15:36:44.931932Z","end":"2026-07-09T15:36:46.393927Z","steps":["trace[1710521546] 'process raft request' (duration: 1.402775844s)","trace[1710521546] 'compare' (duration: 58.946327ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:36:46.394009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:44.931911Z","time spent":"1.46204334s","remote":"127.0.0.1:37612","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-09T15:36:46.394009Z","caller":"traceutil/trace.go:171","msg":"trace[11933394] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"1.461860536s","start":"2026-07-09T15:36:44.932118Z","end":"2026-07-09T15:36:46.393978Z","steps":["trace[11933394] 'process raft request' (duration: 1.461692581s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:46.39411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.456447524s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T15:36:46.394135Z","caller":"traceutil/trace.go:171","msg":"trace[701407801] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1271; }","duration":"1.456548537s","start":"2026-07-09T15:36:44.937578Z","end":"2026-07-09T15:36:46.394126Z","steps":["trace[701407801] 'agreement among raft nodes before linearized reading' (duration: 1.456504115s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:46.39415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:44.93757Z","time spent":"1.456576128s","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-09T15:36:46.394152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:44.932108Z","time spent":"1.461963358s","remote":"127.0.0.1:37612","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-09T15:36:46.394258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:44.931908Z","time spent":"1.462345169s","remote":"127.0.0.1:37374","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-09T15:36:46.394271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.173692877s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:36:46.394304Z","caller":"traceutil/trace.go:171","msg":"trace[525686605] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"1.462251846s","start":"2026-07-09T15:36:44.932047Z","end":"2026-07-09T15:36:46.394299Z","steps":["trace[525686605] 'process raft request' (duration: 1.461723941s)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:36:46.394321Z","caller":"traceutil/trace.go:171","msg":"trace[1272664364] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1271; }","duration":"1.173773901s","start":"2026-07-09T15:36:45.220528Z","end":"2026-07-09T15:36:46.394302Z","steps":["trace[1272664364] 'agreement among raft nodes before linearized reading' (duration: 1.173703989s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:46.394335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:44.93203Z","time spent":"1.462288487s","remote":"127.0.0.1:37612","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-09T15:36:46.394356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:45.22051Z","time spent":"1.173836562s","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"info","ts":"2026-07-09T15:36:46.39438Z","caller":"traceutil/trace.go:171","msg":"trace[1660104963] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"1.462289157s","start":"2026-07-09T15:36:44.932082Z","end":"2026-07-09T15:36:46.394371Z","steps":["trace[1660104963] 'process raft request' (duration: 1.461713481s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:46.394421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:44.932075Z","time spent":"1.462321709s","remote":"127.0.0.1:37612","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-09T15:36:46.394464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.866914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:36:46.394482Z","caller":"traceutil/trace.go:171","msg":"trace[654053370] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1271; }","duration":"709.917977ms","start":"2026-07-09T15:36:45.684559Z","end":"2026-07-09T15:36:46.394477Z","steps":["trace[654053370] 'agreement among raft nodes before linearized reading' (duration: 709.888686ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:46.394495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:45.684539Z","time spent":"709.952467ms","remote":"127.0.0.1:37424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-07-09T15:36:46.394514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.036239941s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:36:46.394544Z","caller":"traceutil/trace.go:171","msg":"trace[911840015] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1271; }","duration":"1.036310964s","start":"2026-07-09T15:36:45.358223Z","end":"2026-07-09T15:36:46.394534Z","steps":["trace[911840015] 'agreement among raft nodes before linearized reading' (duration: 1.036263712s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:46.394572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:45.3582Z","time spent":"1.036363574s","remote":"127.0.0.1:36506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-09T15:36:48.573639Z","caller":"traceutil/trace.go:171","msg":"trace[2074445459] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1326; }","duration":"340.23414ms","start":"2026-07-09T15:36:48.233384Z","end":"2026-07-09T15:36:48.573619Z","steps":["trace[2074445459] 'read index received' (duration: 340.066055ms)","trace[2074445459] 'applied index is now lower than readState.Index' (duration: 166.895µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:36:48.573768Z","caller":"traceutil/trace.go:171","msg":"trace[846006703] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"357.02172ms","start":"2026-07-09T15:36:48.216716Z","end":"2026-07-09T15:36:48.573737Z","steps":["trace[846006703] 'process raft request' (duration: 356.743181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:48.573868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.466927ms","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-09T15:36:48.573911Z","caller":"traceutil/trace.go:171","msg":"trace[1956904927] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1275; }","duration":"340.559209ms","start":"2026-07-09T15:36:48.233341Z","end":"2026-07-09T15:36:48.5739Z","steps":["trace[1956904927] 'agreement among raft nodes before linearized reading' (duration: 340.395865ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:48.573935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:48.216694Z","time spent":"357.155164ms","remote":"127.0.0.1:37612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:36:48.57395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:36:48.233322Z","time spent":"340.620341ms","remote":"127.0.0.1:37612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T15:36:48.574108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.681274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-07-09T15:36:48.574145Z","caller":"traceutil/trace.go:171","msg":"trace[64663812] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1275; }","duration":"277.742617ms","start":"2026-07-09T15:36:48.296394Z","end":"2026-07-09T15:36:48.574136Z","steps":["trace[64663812] 'agreement among raft nodes before linearized reading' (duration: 277.587283ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:48.574319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.108828ms","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-09T15:36:48.574384Z","caller":"traceutil/trace.go:171","msg":"trace[1941264991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1275; }","duration":"175.18984ms","start":"2026-07-09T15:36:48.399175Z","end":"2026-07-09T15:36:48.574364Z","steps":["trace[1941264991] 'agreement among raft nodes before linearized reading' (duration: 175.024705ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:48.574439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.268412ms","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-09T15:36:48.574474Z","caller":"traceutil/trace.go:171","msg":"trace[788412943] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1275; }","duration":"175.315854ms","start":"2026-07-09T15:36:48.399151Z","end":"2026-07-09T15:36:48.574467Z","steps":["trace[788412943] 'agreement among raft nodes before linearized reading' (duration: 175.228402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:48.57451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.95466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:36:48.574547Z","caller":"traceutil/trace.go:171","msg":"trace[117005771] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1275; }","duration":"197.018271ms","start":"2026-07-09T15:36:48.377521Z","end":"2026-07-09T15:36:48.574539Z","steps":["trace[117005771] 'agreement among raft nodes before linearized reading' (duration: 196.947349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:48.574702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.568633ms","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-09T15:36:48.574727Z","caller":"traceutil/trace.go:171","msg":"trace[830504294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1275; }","duration":"175.642814ms","start":"2026-07-09T15:36:48.399078Z","end":"2026-07-09T15:36:48.574721Z","steps":["trace[830504294] 'agreement among raft nodes before linearized reading' (duration: 175.579472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:36:48.5748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.323735ms","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-09T15:36:48.574845Z","caller":"traceutil/trace.go:171","msg":"trace[762427730] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1275; }","duration":"175.406767ms","start":"2026-07-09T15:36:48.399428Z","end":"2026-07-09T15:36:48.574835Z","steps":["trace[762427730] 'agreement among raft nodes before linearized reading' (duration: 175.308364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:37:01.722628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.108738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:37:01.722718Z","caller":"traceutil/trace.go:171","msg":"trace[178006918] linearizableReadLoop","detail":"{readStateIndex:1437; appliedIndex:1436; }","duration":"343.988461ms","start":"2026-07-09T15:37:01.378716Z","end":"2026-07-09T15:37:01.722704Z","steps":["trace[178006918] 'read index received' (duration: 215.674068ms)","trace[178006918] 'applied index is now lower than readState.Index' (duration: 128.313253ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:37:01.722886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.150925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:37:01.722955Z","caller":"traceutil/trace.go:171","msg":"trace[1919645484] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1383; }","duration":"344.26953ms","start":"2026-07-09T15:37:01.37867Z","end":"2026-07-09T15:37:01.722939Z","steps":["trace[1919645484] 'agreement among raft nodes before linearized reading' (duration: 344.136376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:37:01.722967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.465154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-07-09T15:37:01.722992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:37:01.378647Z","time spent":"344.337342ms","remote":"127.0.0.1:37344","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-09T15:37:01.722999Z","caller":"traceutil/trace.go:171","msg":"trace[382036806] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1383; }","duration":"246.535768ms","start":"2026-07-09T15:37:01.476453Z","end":"2026-07-09T15:37:01.722989Z","steps":["trace[382036806] 'agreement among raft nodes before linearized reading' (duration: 246.379803ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:37:01.723156Z","caller":"traceutil/trace.go:171","msg":"trace[1103300954] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"608.169353ms","start":"2026-07-09T15:37:01.114973Z","end":"2026-07-09T15:37:01.723142Z","steps":["trace[1103300954] 'process raft request' (duration: 479.475338ms)","trace[1103300954] 'compare' (duration: 127.84783ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:37:01.723247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:37:01.114949Z","time spent":"608.240806ms","remote":"127.0.0.1:37612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:37:02.210329Z","caller":"traceutil/trace.go:171","msg":"trace[2078637812] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"483.660644ms","start":"2026-07-09T15:37:01.726642Z","end":"2026-07-09T15:37:02.210303Z","steps":["trace[2078637812] 'process raft request' (duration: 483.20617ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:37:02.210467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:37:01.726628Z","time spent":"483.767447ms","remote":"127.0.0.1:37612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:37:49.517653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.244352ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-09T15:37:49.517774Z","caller":"traceutil/trace.go:171","msg":"trace[1793015984] linearizableReadLoop","detail":"{readStateIndex:1667; appliedIndex:1666; }","duration":"139.41932ms","start":"2026-07-09T15:37:49.378335Z","end":"2026-07-09T15:37:49.517755Z","steps":["trace[1793015984] 'read index received' (duration: 31.006293ms)","trace[1793015984] 'applied index is now lower than readState.Index' (duration: 108.411357ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:37:49.518524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.162482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:37:49.518675Z","caller":"traceutil/trace.go:171","msg":"trace[2044125220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1602; }","duration":"140.317636ms","start":"2026-07-09T15:37:49.378288Z","end":"2026-07-09T15:37:49.518605Z","steps":["trace[2044125220] 'agreement among raft nodes before linearized reading' (duration: 139.515472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:04.51093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.00962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.124\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-09T15:38:04.511027Z","caller":"traceutil/trace.go:171","msg":"trace[1883531274] range","detail":"{range_begin:/registry/masterleases/199.19.213.124; range_end:; response_count:1; response_revision:1669; }","duration":"217.169145ms","start":"2026-07-09T15:38:04.293834Z","end":"2026-07-09T15:38:04.511003Z","steps":["trace[1883531274] 'range keys from in-memory index tree' (duration: 216.784182ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:38:04.906377Z","caller":"traceutil/trace.go:171","msg":"trace[295636821] linearizableReadLoop","detail":"{readStateIndex:1737; appliedIndex:1736; }","duration":"389.07476ms","start":"2026-07-09T15:38:04.517273Z","end":"2026-07-09T15:38:04.906348Z","steps":["trace[295636821] 'read index received' (duration: 388.980018ms)","trace[295636821] 'applied index is now lower than readState.Index' (duration: 93.062µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:38:04.906391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:04.512598Z","time spent":"393.785088ms","remote":"127.0.0.1:37374","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-09T15:38:04.906623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.321478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-09T15:38:04.906691Z","caller":"traceutil/trace.go:171","msg":"trace[554504133] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1669; }","duration":"389.416211ms","start":"2026-07-09T15:38:04.517242Z","end":"2026-07-09T15:38:04.906658Z","steps":["trace[554504133] 'agreement among raft nodes before linearized reading' (duration: 389.223736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:04.906735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:04.517227Z","time spent":"389.500394ms","remote":"127.0.0.1:37612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-09T15:38:04.906957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.17765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T15:38:04.907024Z","caller":"traceutil/trace.go:171","msg":"trace[277328420] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1669; }","duration":"349.324953ms","start":"2026-07-09T15:38:04.55768Z","end":"2026-07-09T15:38:04.907004Z","steps":["trace[277328420] 'agreement among raft nodes before linearized reading' (duration: 349.179768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:38:04.907059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:38:04.557664Z","time spent":"349.387745ms","remote":"127.0.0.1:37876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-07-09T15:38:04.907079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.203782ms","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-09T15:38:04.907172Z","caller":"traceutil/trace.go:171","msg":"trace[1229557901] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1669; }","duration":"188.338535ms","start":"2026-07-09T15:38:04.718811Z","end":"2026-07-09T15:38:04.90715Z","steps":["trace[1229557901] 'agreement among raft nodes before linearized reading' (duration: 188.057917ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:38:44.428557Z","caller":"traceutil/trace.go:171","msg":"trace[1043007808] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"126.434556ms","start":"2026-07-09T15:38:44.302088Z","end":"2026-07-09T15:38:44.428522Z","steps":["trace[1043007808] 'process raft request' (duration: 49.119424ms)","trace[1043007808] 'compare' (duration: 77.144337ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:39:41.706116Z","caller":"traceutil/trace.go:171","msg":"trace[834845073] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"154.236995ms","start":"2026-07-09T15:39:41.551825Z","end":"2026-07-09T15:39:41.706062Z","steps":["trace[834845073] 'process raft request' (duration: 90.482089ms)","trace[834845073] 'compare' (duration: 63.654744ms)"],"step_count":2}