{"level":"warn","ts":"2026-05-25T22:02:56.035108Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-25T22:02:56.035212Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.240:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.240:2380","--initial-cluster=instance=https://199.204.45.240:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.240:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.240:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-25T22:02:56.03529Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-25T22:02:56.035305Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-05-25T22:02:56.035324Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-25T22:02:56.035932Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"]} {"level":"info","ts":"2026-05-25T22:02:56.036013Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.240:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-25T22:02:56.363437Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"327.154422ms"} {"level":"info","ts":"2026-05-25T22:02:56.388256Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e46287fb5aa4785","cluster-id":"cae704afa9813e87"} {"level":"info","ts":"2026-05-25T22:02:56.388391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=()"} {"level":"info","ts":"2026-05-25T22:02:56.388426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 0"} {"level":"info","ts":"2026-05-25T22:02:56.38844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e46287fb5aa4785 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-25T22:02:56.388451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 1"} {"level":"info","ts":"2026-05-25T22:02:56.388514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"warn","ts":"2026-05-25T22:02:56.405625Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-25T22:02:56.413646Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-25T22:02:56.421436Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-25T22:02:56.427134Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e46287fb5aa4785","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-25T22:02:56.427284Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e46287fb5aa4785","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-25T22:02:56.427415Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-25T22:02:56.42751Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-25T22:02:56.427539Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-25T22:02:56.432155Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-25T22:02:56.432314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"info","ts":"2026-05-25T22:02:56.432441Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-05-25T22:02:56.432493Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","added-peer-id":"4e46287fb5aa4785","added-peer-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-05-25T22:02:56.432523Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-05-25T22:02:56.432501Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e46287fb5aa4785","initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-25T22:02:56.432563Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-25T22:02:57.390839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 is starting a new election at term 1"} {"level":"info","ts":"2026-05-25T22:02:57.390916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-25T22:02:57.39095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgPreVoteResp from 4e46287fb5aa4785 at term 1"} {"level":"info","ts":"2026-05-25T22:02:57.390972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became candidate at term 2"} {"level":"info","ts":"2026-05-25T22:02:57.390983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgVoteResp from 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-05-25T22:02:57.390999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became leader at term 2"} {"level":"info","ts":"2026-05-25T22:02:57.391018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e46287fb5aa4785 elected leader 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-05-25T22:02:57.391889Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T22:02:57.392818Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e46287fb5aa4785","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.240:2379]}","request-path":"/0/members/4e46287fb5aa4785/attributes","cluster-id":"cae704afa9813e87","publish-timeout":"7s"} {"level":"info","ts":"2026-05-25T22:02:57.392854Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-25T22:02:57.393056Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-25T22:02:57.393295Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-25T22:02:57.393453Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-25T22:02:57.394224Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T22:02:57.39443Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T22:02:57.394465Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T22:02:57.397028Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.240:2379"} {"level":"info","ts":"2026-05-25T22:02:57.397362Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-25T22:02:59.34676Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699488742246026,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:02:59.991129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.836002ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-25T22:02:59.991379Z","caller":"traceutil/trace.go:171","msg":"trace[1675436737] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"1.211594237s","start":"2026-05-25T22:02:58.779766Z","end":"2026-05-25T22:02:59.99136Z","steps":["trace[1675436737] 'process raft request' (duration: 1.211509026s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:02:59.991478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:02:58.779751Z","time spent":"1.211688588s","remote":"127.0.0.1:55064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T22:02:59.991624Z","caller":"traceutil/trace.go:171","msg":"trace[660658967] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"1.213376286s","start":"2026-05-25T22:02:58.778049Z","end":"2026-05-25T22:02:59.991425Z","steps":["trace[660658967] 'process raft request' (duration: 671.557099ms)","trace[660658967] 'compare' (duration: 540.611088ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:02:59.991943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:02:58.778038Z","time spent":"1.213747962s","remote":"127.0.0.1:54770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T22:03:01.153739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.400557ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-25T22:03:01.154131Z","caller":"traceutil/trace.go:171","msg":"trace[2139018970] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"2.306178503s","start":"2026-05-25T22:02:58.847866Z","end":"2026-05-25T22:03:01.154045Z","steps":["trace[2139018970] 'process raft request' (duration: 1.58739489s)","trace[2139018970] 'compare' (duration: 718.249065ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:03:01.15427Z","caller":"traceutil/trace.go:171","msg":"trace[1878812136] transaction","detail":"{read_only:false; number_of_response:0; response_revision:13; }","duration":"2.306188504s","start":"2026-05-25T22:02:58.848046Z","end":"2026-05-25T22:03:01.154235Z","steps":["trace[1878812136] 'process raft request' (duration: 2.305885789s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:01.154449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:02:58.847973Z","time spent":"2.306374297s","remote":"127.0.0.1:54712","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T22:03:01.154464Z","caller":"traceutil/trace.go:171","msg":"trace[1778362561] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"2.305381682s","start":"2026-05-25T22:02:58.849064Z","end":"2026-05-25T22:03:01.154446Z","steps":["trace[1778362561] 'process raft request' (duration: 2.305050056s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:01.154539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:02:58.849045Z","time spent":"2.305462652s","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T22:03:01.154794Z","caller":"traceutil/trace.go:171","msg":"trace[1052687919] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"2.304972014s","start":"2026-05-25T22:02:58.849769Z","end":"2026-05-25T22:03:01.154741Z","steps":["trace[1052687919] 'process raft request' (duration: 2.304633579s)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:01.154912Z","caller":"traceutil/trace.go:171","msg":"trace[104431880] linearizableReadLoop","detail":"{readStateIndex:22; appliedIndex:13; }","duration":"2.309163459s","start":"2026-05-25T22:02:58.845735Z","end":"2026-05-25T22:03:01.154899Z","steps":["trace[104431880] 'read index received' (duration: 603.823857ms)","trace[104431880] 'applied index is now lower than readState.Index' (duration: 1.705338042s)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:03:01.154926Z","caller":"traceutil/trace.go:171","msg":"trace[1806764857] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"2.305223327s","start":"2026-05-25T22:02:58.849689Z","end":"2026-05-25T22:03:01.154913Z","steps":["trace[1806764857] 'process raft request' (duration: 2.304481147s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:01.154965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:02:58.849762Z","time spent":"2.305123626s","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T22:03:01.154986Z","caller":"traceutil/trace.go:171","msg":"trace[814652062] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"2.305122196s","start":"2026-05-25T22:02:58.849855Z","end":"2026-05-25T22:03:01.154977Z","steps":["trace[814652062] 'process raft request' (duration: 2.304584488s)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:01.154799Z","caller":"traceutil/trace.go:171","msg":"trace[1618771257] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"2.305697906s","start":"2026-05-25T22:02:58.84906Z","end":"2026-05-25T22:03:01.154758Z","steps":["trace[1618771257] 'process raft request' (duration: 2.304954954s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:01.155011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:02:58.849679Z","time spent":"2.305293968s","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T22:03:01.155044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:02:58.84984Z","time spent":"2.305175497s","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T22:03:01.155131Z","caller":"traceutil/trace.go:171","msg":"trace[201357182] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"1.15427051s","start":"2026-05-25T22:03:00.000842Z","end":"2026-05-25T22:03:01.155112Z","steps":["trace[201357182] 'process raft request' (duration: 1.153979866s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:01.155235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.335987229s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-05-25T22:03:01.155291Z","caller":"traceutil/trace.go:171","msg":"trace[629183280] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"2.3360694s","start":"2026-05-25T22:02:58.819208Z","end":"2026-05-25T22:03:01.155277Z","steps":["trace[629183280] 'agreement among raft nodes before linearized reading' (duration: 2.335894897s)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:01.155317Z","caller":"traceutil/trace.go:171","msg":"trace[736370210] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"1.158684269s","start":"2026-05-25T22:02:59.996616Z","end":"2026-05-25T22:03:01.1553Z","steps":["trace[736370210] 'process raft request' (duration: 1.157943408s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:01.155369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.375617659s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-25T22:03:01.155399Z","caller":"traceutil/trace.go:171","msg":"trace[996670397] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:20; }","duration":"2.37567853s","start":"2026-05-25T22:02:58.779713Z","end":"2026-05-25T22:03:01.155392Z","steps":["trace[996670397] 'agreement among raft nodes before linearized reading' (duration: 2.37563697s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:01.155411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:02:59.996596Z","time spent":"1.15876567s","remote":"127.0.0.1:55064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T22:03:01.155464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.381598073s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-05-25T22:03:01.155492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.306596779s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-p2l5b\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-05-25T22:03:01.155494Z","caller":"traceutil/trace.go:171","msg":"trace[1694501798] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"2.381644014s","start":"2026-05-25T22:02:58.773843Z","end":"2026-05-25T22:03:01.155487Z","steps":["trace[1694501798] 'agreement among raft nodes before linearized reading' (duration: 2.381594723s)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:01.155515Z","caller":"traceutil/trace.go:171","msg":"trace[783280683] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-p2l5b; range_end:; response_count:1; response_revision:20; }","duration":"2.30665468s","start":"2026-05-25T22:02:58.848854Z","end":"2026-05-25T22:03:01.155508Z","steps":["trace[783280683] 'agreement among raft nodes before linearized reading' (duration: 2.306607969s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:01.155422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:02:58.779701Z","time spent":"2.375716361s","remote":"127.0.0.1:55000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-05-25T22:03:01.155519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:02:58.773836Z","time spent":"2.381677974s","remote":"127.0.0.1:54712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-25T22:03:01.155497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:02:58.849053Z","time spent":"2.306100892s","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T22:03:01.155642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.382159703s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-05-25T22:03:01.155332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:02:58.819194Z","time spent":"2.336130181s","remote":"127.0.0.1:54712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-25T22:03:01.155543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:02:58.848844Z","time spent":"2.306694882s","remote":"127.0.0.1:54834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":884,"request content":"key:\"/registry/certificatesigningrequests/csr-p2l5b\" "} {"level":"warn","ts":"2026-05-25T22:03:01.155799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.383798682s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-25T22:03:01.155824Z","caller":"traceutil/trace.go:171","msg":"trace[26719342] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20; }","duration":"1.383877463s","start":"2026-05-25T22:02:59.771939Z","end":"2026-05-25T22:03:01.155816Z","steps":["trace[26719342] 'agreement among raft nodes before linearized reading' (duration: 1.383840122s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:01.155845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:02:59.771926Z","time spent":"1.383914194s","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-25T22:03:01.155665Z","caller":"traceutil/trace.go:171","msg":"trace[2075816598] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"2.382181963s","start":"2026-05-25T22:02:58.773476Z","end":"2026-05-25T22:03:01.155658Z","steps":["trace[2075816598] 'agreement among raft nodes before linearized reading' (duration: 2.382144193s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:01.155991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.213190647s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-25T22:03:01.156061Z","caller":"traceutil/trace.go:171","msg":"trace[535618478] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:20; }","duration":"2.213323149s","start":"2026-05-25T22:02:58.942706Z","end":"2026-05-25T22:03:01.156029Z","steps":["trace[535618478] 'agreement among raft nodes before linearized reading' (duration: 2.213235887s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:01.156105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:02:58.942683Z","time spent":"2.213412121s","remote":"127.0.0.1:54850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-05-25T22:03:01.156062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:02:58.773468Z","time spent":"2.382511178s","remote":"127.0.0.1:54712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-25T22:03:01.15626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.488947225s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-25T22:03:01.156294Z","caller":"traceutil/trace.go:171","msg":"trace[1680058321] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:20; }","duration":"1.489024737s","start":"2026-05-25T22:02:59.667262Z","end":"2026-05-25T22:03:01.156286Z","steps":["trace[1680058321] 'agreement among raft nodes before linearized reading' (duration: 1.488972546s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:01.156318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:02:59.667236Z","time spent":"1.489077567s","remote":"127.0.0.1:54956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-05-25T22:03:01.156428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.487063207s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-25T22:03:01.156453Z","caller":"traceutil/trace.go:171","msg":"trace[605810152] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:20; }","duration":"1.487111697s","start":"2026-05-25T22:02:59.669335Z","end":"2026-05-25T22:03:01.156446Z","steps":["trace[605810152] 'agreement among raft nodes before linearized reading' (duration: 1.487071136s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:01.156475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:02:59.669324Z","time spent":"1.487145537s","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-05-25T22:03:01.157624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:00.00083Z","time spent":"1.154477823s","remote":"127.0.0.1:54770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T22:03:01.158463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:02:58.847847Z","time spent":"2.306393758s","remote":"127.0.0.1:54712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T22:03:12.597605Z","caller":"traceutil/trace.go:171","msg":"trace[20736312] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"194.9787ms","start":"2026-05-25T22:03:12.402605Z","end":"2026-05-25T22:03:12.597584Z","steps":["trace[20736312] 'process raft request' (duration: 194.836138ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:12.722253Z","caller":"traceutil/trace.go:171","msg":"trace[1293131057] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"106.981257ms","start":"2026-05-25T22:03:12.615247Z","end":"2026-05-25T22:03:12.722229Z","steps":["trace[1293131057] 'process raft request' (duration: 106.845905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:13.418427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.025107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-25T22:03:13.418492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.670094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:03:13.418551Z","caller":"traceutil/trace.go:171","msg":"trace[271404578] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:371; }","duration":"413.180939ms","start":"2026-05-25T22:03:13.005346Z","end":"2026-05-25T22:03:13.418527Z","steps":["trace[271404578] 'range keys from in-memory index tree' (duration: 412.913555ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:13.418569Z","caller":"traceutil/trace.go:171","msg":"trace[243595787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:371; }","duration":"548.805356ms","start":"2026-05-25T22:03:12.869743Z","end":"2026-05-25T22:03:13.418549Z","steps":["trace[243595787] 'range keys from in-memory index tree' (duration: 548.576833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:13.418609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:12.869722Z","time spent":"548.879837ms","remote":"127.0.0.1:54594","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-05-25T22:03:13.418612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:13.005307Z","time spent":"413.289371ms","remote":"127.0.0.1:54850","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-05-25T22:03:13.419146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.113018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-25T22:03:13.41921Z","caller":"traceutil/trace.go:171","msg":"trace[222368011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:371; }","duration":"123.310891ms","start":"2026-05-25T22:03:13.295882Z","end":"2026-05-25T22:03:13.419193Z","steps":["trace[222368011] 'range keys from in-memory index tree' (duration: 123.021886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:13.419241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.329519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-25T22:03:13.41934Z","caller":"traceutil/trace.go:171","msg":"trace[281534508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:371; }","duration":"125.492073ms","start":"2026-05-25T22:03:13.293829Z","end":"2026-05-25T22:03:13.419321Z","steps":["trace[281534508] 'range keys from in-memory index tree' (duration: 125.187807ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:13.667088Z","caller":"traceutil/trace.go:171","msg":"trace[1073276523] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"242.676994ms","start":"2026-05-25T22:03:13.424378Z","end":"2026-05-25T22:03:13.667055Z","steps":["trace[1073276523] 'process raft request' (duration: 242.458682ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:13.809069Z","caller":"traceutil/trace.go:171","msg":"trace[1710762943] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:384; }","duration":"238.880291ms","start":"2026-05-25T22:03:13.570165Z","end":"2026-05-25T22:03:13.809046Z","steps":["trace[1710762943] 'read index received' (duration: 96.65261ms)","trace[1710762943] 'applied index is now lower than readState.Index' (duration: 142.226601ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:03:13.809175Z","caller":"traceutil/trace.go:171","msg":"trace[953914548] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"382.221636ms","start":"2026-05-25T22:03:13.426928Z","end":"2026-05-25T22:03:13.80915Z","steps":["trace[953914548] 'process raft request' (duration: 382.017984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:13.809231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.039443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4796"} {"level":"info","ts":"2026-05-25T22:03:13.809227Z","caller":"traceutil/trace.go:171","msg":"trace[2064521767] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"156.575006ms","start":"2026-05-25T22:03:13.652599Z","end":"2026-05-25T22:03:13.809174Z","steps":["trace[2064521767] 'process raft request' (duration: 156.390563ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:13.809973Z","caller":"traceutil/trace.go:171","msg":"trace[822453627] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:375; }","duration":"239.788965ms","start":"2026-05-25T22:03:13.57011Z","end":"2026-05-25T22:03:13.809899Z","steps":["trace[822453627] 'agreement among raft nodes before linearized reading' (duration: 239.044063ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:13.809319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:13.42691Z","time spent":"382.330879ms","remote":"127.0.0.1:54850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:03:13.809369Z","caller":"traceutil/trace.go:171","msg":"trace[293472162] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"382.577842ms","start":"2026-05-25T22:03:13.426779Z","end":"2026-05-25T22:03:13.809356Z","steps":["trace[293472162] 'process raft request' (duration: 373.369291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:13.810242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:13.426762Z","time spent":"383.429134ms","remote":"127.0.0.1:54850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:03:13.930018Z","caller":"traceutil/trace.go:171","msg":"trace[1100296405] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"109.553204ms","start":"2026-05-25T22:03:13.820436Z","end":"2026-05-25T22:03:13.929989Z","steps":["trace[1100296405] 'process raft request' (duration: 108.845294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:15.954574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.038737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-25T22:03:15.954651Z","caller":"traceutil/trace.go:171","msg":"trace[1456505977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:378; }","duration":"140.205899ms","start":"2026-05-25T22:03:15.814428Z","end":"2026-05-25T22:03:15.954634Z","steps":["trace[1456505977] 'range keys from in-memory index tree' (duration: 139.915445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:15.954577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.163349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-25T22:03:15.954796Z","caller":"traceutil/trace.go:171","msg":"trace[663467855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:378; }","duration":"140.411382ms","start":"2026-05-25T22:03:15.814365Z","end":"2026-05-25T22:03:15.954777Z","steps":["trace[663467855] 'range keys from in-memory index tree' (duration: 140.096078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:21.973963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.606663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:03:21.974068Z","caller":"traceutil/trace.go:171","msg":"trace[383540037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:392; }","duration":"105.761385ms","start":"2026-05-25T22:03:21.868284Z","end":"2026-05-25T22:03:21.974046Z","steps":["trace[383540037] 'range keys from in-memory index tree' (duration: 105.521301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:23.114065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.746607ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-25T22:03:23.114188Z","caller":"traceutil/trace.go:171","msg":"trace[1357741148] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"331.513179ms","start":"2026-05-25T22:03:22.782656Z","end":"2026-05-25T22:03:23.11417Z","steps":["trace[1357741148] 'process raft request' (duration: 198.60088ms)","trace[1357741148] 'compare' (duration: 132.621105ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:03:23.114241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:22.782634Z","time spent":"331.58516ms","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T22:03:23.114902Z","caller":"traceutil/trace.go:171","msg":"trace[916518333] linearizableReadLoop","detail":"{readStateIndex:416; appliedIndex:413; }","duration":"245.698261ms","start":"2026-05-25T22:03:22.869178Z","end":"2026-05-25T22:03:23.114877Z","steps":["trace[916518333] 'read index received' (duration: 112.034113ms)","trace[916518333] 'applied index is now lower than readState.Index' (duration: 133.663198ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:03:23.114933Z","caller":"traceutil/trace.go:171","msg":"trace[1547130293] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"332.151907ms","start":"2026-05-25T22:03:22.782753Z","end":"2026-05-25T22:03:23.114904Z","steps":["trace[1547130293] 'process raft request' (duration: 331.950264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:23.11502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.819323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:03:23.115054Z","caller":"traceutil/trace.go:171","msg":"trace[1666559489] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:401; }","duration":"245.906984ms","start":"2026-05-25T22:03:22.869136Z","end":"2026-05-25T22:03:23.115043Z","steps":["trace[1666559489] 'agreement among raft nodes before linearized reading' (duration: 245.833433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:23.115073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:22.782733Z","time spent":"332.275099ms","remote":"127.0.0.1:54776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3563,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T22:03:23.115132Z","caller":"traceutil/trace.go:171","msg":"trace[161134788] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"332.203918ms","start":"2026-05-25T22:03:22.782908Z","end":"2026-05-25T22:03:23.115112Z","steps":["trace[161134788] 'process raft request' (duration: 331.904893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:23.115251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:22.782902Z","time spent":"332.27028ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T22:03:23.68959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.260427ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-25T22:03:23.689813Z","caller":"traceutil/trace.go:171","msg":"trace[1942397608] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"395.391876ms","start":"2026-05-25T22:03:23.294404Z","end":"2026-05-25T22:03:23.689795Z","steps":["trace[1942397608] 'process raft request' (duration: 395.347285ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:23.689832Z","caller":"traceutil/trace.go:171","msg":"trace[925557209] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"397.06664ms","start":"2026-05-25T22:03:23.292728Z","end":"2026-05-25T22:03:23.689795Z","steps":["trace[925557209] 'process raft request' (duration: 253.561069ms)","trace[925557209] 'compare' (duration: 143.031825ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:03:23.689904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:23.294396Z","time spent":"395.469207ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:03:23.689889Z","caller":"traceutil/trace.go:171","msg":"trace[854796894] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"396.695494ms","start":"2026-05-25T22:03:23.29313Z","end":"2026-05-25T22:03:23.689826Z","steps":["trace[854796894] 'process raft request' (duration: 396.545392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:23.69Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:23.292701Z","time spent":"397.237132ms","remote":"127.0.0.1:54776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3563,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T22:03:23.69002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:23.293119Z","time spent":"396.832676ms","remote":"127.0.0.1:55114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:03:34.472203Z","caller":"traceutil/trace.go:171","msg":"trace[550204209] linearizableReadLoop","detail":"{readStateIndex:468; appliedIndex:467; }","duration":"418.635717ms","start":"2026-05-25T22:03:34.053551Z","end":"2026-05-25T22:03:34.472186Z","steps":["trace[550204209] 'read index received' (duration: 418.480425ms)","trace[550204209] 'applied index is now lower than readState.Index' (duration: 154.642µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:03:34.47233Z","caller":"traceutil/trace.go:171","msg":"trace[62826555] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"488.771577ms","start":"2026-05-25T22:03:33.983522Z","end":"2026-05-25T22:03:34.472293Z","steps":["trace[62826555] 'process raft request' (duration: 488.551644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:34.472383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.812619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-25T22:03:34.472425Z","caller":"traceutil/trace.go:171","msg":"trace[1420992734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:450; }","duration":"418.912989ms","start":"2026-05-25T22:03:34.053501Z","end":"2026-05-25T22:03:34.472414Z","steps":["trace[1420992734] 'agreement among raft nodes before linearized reading' (duration: 418.826018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:34.472417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.809549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-25T22:03:34.472454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:34.053488Z","time spent":"418.960641ms","remote":"127.0.0.1:54850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-25T22:03:34.472479Z","caller":"traceutil/trace.go:171","msg":"trace[1026387255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:450; }","duration":"418.92196ms","start":"2026-05-25T22:03:34.05354Z","end":"2026-05-25T22:03:34.472462Z","steps":["trace[1026387255] 'agreement among raft nodes before linearized reading' (duration: 418.782068ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:34.472517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:34.053523Z","time spent":"418.988281ms","remote":"127.0.0.1:54850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-25T22:03:34.472509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:33.983497Z","time spent":"488.925848ms","remote":"127.0.0.1:54850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T22:03:36.984299Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699488742246992,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-25T22:03:36.988764Z","caller":"traceutil/trace.go:171","msg":"trace[1504500218] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"782.107065ms","start":"2026-05-25T22:03:36.206636Z","end":"2026-05-25T22:03:36.988743Z","steps":["trace[1504500218] 'process raft request' (duration: 781.974214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:36.988896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:36.206615Z","time spent":"782.225577ms","remote":"127.0.0.1:54656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T22:03:36.999828Z","caller":"traceutil/trace.go:171","msg":"trace[860992077] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"790.696395ms","start":"2026-05-25T22:03:36.209113Z","end":"2026-05-25T22:03:36.99981Z","steps":["trace[860992077] 'process raft request' (duration: 790.522963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:37.000068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:36.209094Z","time spent":"790.806406ms","remote":"127.0.0.1:54656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T22:03:37.000131Z","caller":"traceutil/trace.go:171","msg":"trace[38736801] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"733.268765ms","start":"2026-05-25T22:03:36.266816Z","end":"2026-05-25T22:03:37.000085Z","steps":["trace[38736801] 'process raft request' (duration: 733.105823ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:37.000182Z","caller":"traceutil/trace.go:171","msg":"trace[1817853975] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"782.503541ms","start":"2026-05-25T22:03:36.21767Z","end":"2026-05-25T22:03:37.000173Z","steps":["trace[1817853975] 'process raft request' (duration: 782.070995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:37.00024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:36.217654Z","time spent":"782.566952ms","remote":"127.0.0.1:54656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T22:03:37.000245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:36.266812Z","time spent":"733.395247ms","remote":"127.0.0.1:54656","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-05-25T22:03:37.000374Z","caller":"traceutil/trace.go:171","msg":"trace[1226560729] linearizableReadLoop","detail":"{readStateIndex:518; appliedIndex:506; }","duration":"516.425431ms","start":"2026-05-25T22:03:36.483927Z","end":"2026-05-25T22:03:37.000352Z","steps":["trace[1226560729] 'read index received' (duration: 504.613371ms)","trace[1226560729] 'applied index is now lower than readState.Index' (duration: 11.81119ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:03:37.000388Z","caller":"traceutil/trace.go:171","msg":"trace[1380963803] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"733.586729ms","start":"2026-05-25T22:03:36.266761Z","end":"2026-05-25T22:03:37.000348Z","steps":["trace[1380963803] 'process raft request' (duration: 733.125623ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:37.000429Z","caller":"traceutil/trace.go:171","msg":"trace[1942764723] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"729.239564ms","start":"2026-05-25T22:03:36.271174Z","end":"2026-05-25T22:03:37.000413Z","steps":["trace[1942764723] 'process raft request' (duration: 728.908319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:37.00046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:36.266745Z","time spent":"733.696811ms","remote":"127.0.0.1:54656","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-05-25T22:03:37.00047Z","caller":"traceutil/trace.go:171","msg":"trace[1811593344] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"726.198586ms","start":"2026-05-25T22:03:36.274253Z","end":"2026-05-25T22:03:37.000452Z","steps":["trace[1811593344] 'process raft request' (duration: 725.917073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:37.000494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:36.271168Z","time spent":"729.296195ms","remote":"127.0.0.1:54656","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-05-25T22:03:37.000517Z","caller":"traceutil/trace.go:171","msg":"trace[741190544] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"608.510881ms","start":"2026-05-25T22:03:36.391999Z","end":"2026-05-25T22:03:37.000509Z","steps":["trace[741190544] 'process raft request' (duration: 608.303639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:37.000543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:36.391974Z","time spent":"608.556561ms","remote":"127.0.0.1:54656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T22:03:37.000571Z","caller":"traceutil/trace.go:171","msg":"trace[2098886384] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"683.849359ms","start":"2026-05-25T22:03:36.316714Z","end":"2026-05-25T22:03:37.000563Z","steps":["trace[2098886384] 'process raft request' (duration: 683.501265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:37.000539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:36.274242Z","time spent":"726.251027ms","remote":"127.0.0.1:54656","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-05-25T22:03:37.000611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:36.3167Z","time spent":"683.893969ms","remote":"127.0.0.1:54656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T22:03:37.000614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.684284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-25T22:03:37.00065Z","caller":"traceutil/trace.go:171","msg":"trace[1440504013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:500; }","duration":"516.753175ms","start":"2026-05-25T22:03:36.483886Z","end":"2026-05-25T22:03:37.000639Z","steps":["trace[1440504013] 'agreement among raft nodes before linearized reading' (duration: 516.673704ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:37.000661Z","caller":"traceutil/trace.go:171","msg":"trace[112273798] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"733.775962ms","start":"2026-05-25T22:03:36.266877Z","end":"2026-05-25T22:03:37.000653Z","steps":["trace[112273798] 'process raft request' (duration: 733.102103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:37.000686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:36.483867Z","time spent":"516.810545ms","remote":"127.0.0.1:54850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-25T22:03:37.000687Z","caller":"traceutil/trace.go:171","msg":"trace[229796269] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"733.65493ms","start":"2026-05-25T22:03:36.267002Z","end":"2026-05-25T22:03:37.000657Z","steps":["trace[229796269] 'process raft request' (duration: 733.039391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:37.000696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:36.266873Z","time spent":"733.807352ms","remote":"127.0.0.1:54656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T22:03:37.000718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:36.266998Z","time spent":"733.706631ms","remote":"127.0.0.1:54656","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-05-25T22:03:37.000709Z","caller":"traceutil/trace.go:171","msg":"trace[1126100781] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"750.207941ms","start":"2026-05-25T22:03:36.250461Z","end":"2026-05-25T22:03:37.000669Z","steps":["trace[1126100781] 'process raft request' (duration: 749.326239ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:37.000884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:36.250444Z","time spent":"750.376033ms","remote":"127.0.0.1:54656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T22:03:37.021914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.898593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-25T22:03:37.021959Z","caller":"traceutil/trace.go:171","msg":"trace[1112162172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:502; }","duration":"537.996953ms","start":"2026-05-25T22:03:36.483953Z","end":"2026-05-25T22:03:37.02195Z","steps":["trace[1112162172] 'agreement among raft nodes before linearized reading' (duration: 537.915022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:37.021979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:36.483922Z","time spent":"538.053195ms","remote":"127.0.0.1:54850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-25T22:03:37.022117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.277156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:03:37.022131Z","caller":"traceutil/trace.go:171","msg":"trace[107407033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:502; }","duration":"153.313036ms","start":"2026-05-25T22:03:36.868814Z","end":"2026-05-25T22:03:37.022127Z","steps":["trace[107407033] 'agreement among raft nodes before linearized reading' (duration: 153.291196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:37.022256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.635048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-25T22:03:37.022312Z","caller":"traceutil/trace.go:171","msg":"trace[2023489152] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:502; }","duration":"189.737629ms","start":"2026-05-25T22:03:36.832564Z","end":"2026-05-25T22:03:37.022301Z","steps":["trace[2023489152] 'agreement among raft nodes before linearized reading' (duration: 189.584657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:38.11697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.999307ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T22:03:38.117074Z","caller":"traceutil/trace.go:171","msg":"trace[1227467815] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:580; }","duration":"196.835632ms","start":"2026-05-25T22:03:37.920223Z","end":"2026-05-25T22:03:38.117059Z","steps":["trace[1227467815] 'read index received' (duration: 70.651833ms)","trace[1227467815] 'applied index is now lower than readState.Index' (duration: 126.182529ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:03:38.117098Z","caller":"traceutil/trace.go:171","msg":"trace[783499507] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"200.382417ms","start":"2026-05-25T22:03:37.916688Z","end":"2026-05-25T22:03:38.11707Z","steps":["trace[783499507] 'process raft request' (duration: 74.180018ms)","trace[783499507] 'compare' (duration: 125.823735ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:03:38.117156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.925453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:03:38.117189Z","caller":"traceutil/trace.go:171","msg":"trace[1381215552] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:559; }","duration":"196.972864ms","start":"2026-05-25T22:03:37.920204Z","end":"2026-05-25T22:03:38.117177Z","steps":["trace[1381215552] 'agreement among raft nodes before linearized reading' (duration: 196.919023ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:38.135801Z","caller":"traceutil/trace.go:171","msg":"trace[818707839] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"193.211697ms","start":"2026-05-25T22:03:37.942569Z","end":"2026-05-25T22:03:38.13578Z","steps":["trace[818707839] 'process raft request' (duration: 193.120836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:38.167059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.776962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:03:38.167134Z","caller":"traceutil/trace.go:171","msg":"trace[1217917350] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:561; }","duration":"246.872493ms","start":"2026-05-25T22:03:37.920244Z","end":"2026-05-25T22:03:38.167116Z","steps":["trace[1217917350] 'agreement among raft nodes before linearized reading' (duration: 246.733501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:38.167123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.824073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T22:03:38.16729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.068783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:03:38.167305Z","caller":"traceutil/trace.go:171","msg":"trace[56581717] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:561; }","duration":"247.026775ms","start":"2026-05-25T22:03:37.920256Z","end":"2026-05-25T22:03:38.167283Z","steps":["trace[56581717] 'agreement among raft nodes before linearized reading' (duration: 246.776792ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:38.16734Z","caller":"traceutil/trace.go:171","msg":"trace[1643163677] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:561; }","duration":"178.131163ms","start":"2026-05-25T22:03:37.989198Z","end":"2026-05-25T22:03:38.167329Z","steps":["trace[1643163677] 'agreement among raft nodes before linearized reading' (duration: 178.067503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:38.16715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.140354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T22:03:38.16743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.237544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:03:38.167444Z","caller":"traceutil/trace.go:171","msg":"trace[156851136] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:561; }","duration":"178.504908ms","start":"2026-05-25T22:03:37.98893Z","end":"2026-05-25T22:03:38.167435Z","steps":["trace[156851136] 'agreement among raft nodes before linearized reading' (duration: 178.027472ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:38.167241Z","caller":"traceutil/trace.go:171","msg":"trace[161332355] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"130.026087ms","start":"2026-05-25T22:03:38.037195Z","end":"2026-05-25T22:03:38.167221Z","steps":["trace[161332355] 'process raft request' (duration: 128.830352ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:38.167517Z","caller":"traceutil/trace.go:171","msg":"trace[1927885558] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:561; }","duration":"178.293025ms","start":"2026-05-25T22:03:37.989162Z","end":"2026-05-25T22:03:38.167455Z","steps":["trace[1927885558] 'agreement among raft nodes before linearized reading' (duration: 178.186614ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:42.491561Z","caller":"traceutil/trace.go:171","msg":"trace[1500078828] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"349.564717ms","start":"2026-05-25T22:03:42.141962Z","end":"2026-05-25T22:03:42.491527Z","steps":["trace[1500078828] 'read index received' (duration: 303.75432ms)","trace[1500078828] 'applied index is now lower than readState.Index' (duration: 45.809467ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:03:42.491696Z","caller":"traceutil/trace.go:171","msg":"trace[1968473505] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"364.135517ms","start":"2026-05-25T22:03:42.127539Z","end":"2026-05-25T22:03:42.491675Z","steps":["trace[1968473505] 'process raft request' (duration: 318.302269ms)","trace[1968473505] 'compare' (duration: 45.566994ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:03:42.491822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:42.1275Z","time spent":"364.231609ms","remote":"127.0.0.1:54850","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-05-25T22:03:42.491981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.622268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-25T22:03:42.492083Z","caller":"traceutil/trace.go:171","msg":"trace[888362993] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:576; }","duration":"349.736599ms","start":"2026-05-25T22:03:42.142307Z","end":"2026-05-25T22:03:42.492043Z","steps":["trace[888362993] 'agreement among raft nodes before linearized reading' (duration: 349.552777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:42.492131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:42.142295Z","time spent":"349.82831ms","remote":"127.0.0.1:54850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-25T22:03:42.492283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.324346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-05-25T22:03:42.492326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.253883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:03:42.492352Z","caller":"traceutil/trace.go:171","msg":"trace[1953851468] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:576; }","duration":"350.472169ms","start":"2026-05-25T22:03:42.141851Z","end":"2026-05-25T22:03:42.492324Z","steps":["trace[1953851468] 'agreement among raft nodes before linearized reading' (duration: 350.383158ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:42.492367Z","caller":"traceutil/trace.go:171","msg":"trace[521920477] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:576; }","duration":"181.319564ms","start":"2026-05-25T22:03:42.311035Z","end":"2026-05-25T22:03:42.492355Z","steps":["trace[521920477] 'agreement among raft nodes before linearized reading' (duration: 181.265403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:42.492385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:42.141834Z","time spent":"350.543849ms","remote":"127.0.0.1:54762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-05-25T22:03:43.27856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.777317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T22:03:43.278768Z","caller":"traceutil/trace.go:171","msg":"trace[1636610705] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"176.382434ms","start":"2026-05-25T22:03:43.102367Z","end":"2026-05-25T22:03:43.278749Z","steps":["trace[1636610705] 'process raft request' (duration: 176.321263ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:43.278843Z","caller":"traceutil/trace.go:171","msg":"trace[555351093] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"185.657209ms","start":"2026-05-25T22:03:43.093156Z","end":"2026-05-25T22:03:43.278813Z","steps":["trace[555351093] 'compare' (duration: 179.621175ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:43.344125Z","caller":"traceutil/trace.go:171","msg":"trace[1374234785] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:607; }","duration":"224.138363ms","start":"2026-05-25T22:03:43.119953Z","end":"2026-05-25T22:03:43.344092Z","steps":["trace[1374234785] 'read index received' (duration: 138.953064ms)","trace[1374234785] 'applied index is now lower than readState.Index' (duration: 85.184109ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:03:43.344279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.316886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:03:43.344251Z","caller":"traceutil/trace.go:171","msg":"trace[2143389878] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"241.220324ms","start":"2026-05-25T22:03:43.102988Z","end":"2026-05-25T22:03:43.344208Z","steps":["trace[2143389878] 'process raft request' (duration: 240.93278ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:43.344328Z","caller":"traceutil/trace.go:171","msg":"trace[833404283] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:587; }","duration":"224.425348ms","start":"2026-05-25T22:03:43.119887Z","end":"2026-05-25T22:03:43.344312Z","steps":["trace[833404283] 'agreement among raft nodes before linearized reading' (duration: 224.321536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:44.228236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.087681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-brp8q\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-25T22:03:44.228336Z","caller":"traceutil/trace.go:171","msg":"trace[1536641061] range","detail":"{range_begin:/registry/pods/kube-system/cilium-brp8q; range_end:; response_count:1; response_revision:587; }","duration":"542.271563ms","start":"2026-05-25T22:03:43.686043Z","end":"2026-05-25T22:03:44.228315Z","steps":["trace[1536641061] 'range keys from in-memory index tree' (duration: 541.869059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:44.22841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:43.686018Z","time spent":"542.380644ms","remote":"127.0.0.1:54776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-brp8q\" "} {"level":"info","ts":"2026-05-25T22:03:44.285026Z","caller":"traceutil/trace.go:171","msg":"trace[1622520264] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"555.232281ms","start":"2026-05-25T22:03:43.729767Z","end":"2026-05-25T22:03:44.284999Z","steps":["trace[1622520264] 'process raft request' (duration: 554.622903ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:44.285194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:43.729714Z","time spent":"555.386852ms","remote":"127.0.0.1:54692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036101,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T22:03:46.035015Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.315518468s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-25T22:03:46.193182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.183482212s","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-05-25T22:03:46.193339Z","caller":"traceutil/trace.go:171","msg":"trace[1264975672] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:588; }","duration":"1.183641804s","start":"2026-05-25T22:03:45.009676Z","end":"2026-05-25T22:03:46.193317Z","steps":["trace[1264975672] 'range keys from in-memory index tree' (duration: 1.183469152s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:46.19333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.847466813s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T22:03:46.19353Z","caller":"traceutil/trace.go:171","msg":"trace[382821924] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"1.836638751s","start":"2026-05-25T22:03:44.356871Z","end":"2026-05-25T22:03:46.193509Z","steps":["trace[382821924] 'process raft request' (duration: 1.83655452s)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:46.193556Z","caller":"traceutil/trace.go:171","msg":"trace[231388400] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"1.47413991s","start":"2026-05-25T22:03:44.719403Z","end":"2026-05-25T22:03:46.193543Z","steps":["trace[231388400] 'process raft request' (duration: 1.47409169s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:46.193617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:44.719376Z","time spent":"1.474211471s","remote":"127.0.0.1:54850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:03:46.193777Z","caller":"traceutil/trace.go:171","msg":"trace[1510969624] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"1.952405963s","start":"2026-05-25T22:03:44.241357Z","end":"2026-05-25T22:03:46.193763Z","steps":["trace[1510969624] 'process raft request' (duration: 104.416783ms)","trace[1510969624] 'compare' (duration: 1.847308901s)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:03:46.193847Z","caller":"traceutil/trace.go:171","msg":"trace[1160374114] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:611; }","duration":"1.667409163s","start":"2026-05-25T22:03:44.526424Z","end":"2026-05-25T22:03:46.193833Z","steps":["trace[1160374114] 'read index received' (duration: 34.33µs)","trace[1160374114] 'applied index is now lower than readState.Index' (duration: 1.667373623s)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:03:46.193977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.324893198s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:03:46.193997Z","caller":"traceutil/trace.go:171","msg":"trace[489942062] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"1.324951798s","start":"2026-05-25T22:03:44.869039Z","end":"2026-05-25T22:03:46.19399Z","steps":["trace[489942062] 'agreement among raft nodes before linearized reading' (duration: 1.324920618s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:46.19401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.667588086s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-25T22:03:46.194019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:44.869019Z","time spent":"1.324993339s","remote":"127.0.0.1:54594","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-05-25T22:03:46.193858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:44.241335Z","time spent":"1.952471753s","remote":"127.0.0.1:54776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:03:46.194038Z","caller":"traceutil/trace.go:171","msg":"trace[1911248461] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:591; }","duration":"1.667653846s","start":"2026-05-25T22:03:44.526374Z","end":"2026-05-25T22:03:46.194028Z","steps":["trace[1911248461] 'agreement among raft nodes before linearized reading' (duration: 1.667539285s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:46.194152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.53781ms","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-05-25T22:03:46.194169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:44.526355Z","time spent":"1.667800147s","remote":"127.0.0.1:54850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-25T22:03:46.194196Z","caller":"traceutil/trace.go:171","msg":"trace[721579682] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:591; }","duration":"845.614941ms","start":"2026-05-25T22:03:45.348568Z","end":"2026-05-25T22:03:46.194183Z","steps":["trace[721579682] 'agreement among raft nodes before linearized reading' (duration: 845.475879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:46.194213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.994501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-25T22:03:46.194231Z","caller":"traceutil/trace.go:171","msg":"trace[1127024470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"911.073641ms","start":"2026-05-25T22:03:45.283152Z","end":"2026-05-25T22:03:46.194226Z","steps":["trace[1127024470] 'agreement among raft nodes before linearized reading' (duration: 911.025611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:46.19425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:45.283121Z","time spent":"911.125862ms","remote":"127.0.0.1:54850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-25T22:03:46.194225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:45.348547Z","time spent":"845.672171ms","remote":"127.0.0.1:54850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-25T22:03:46.194166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.489793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-25T22:03:46.194352Z","caller":"traceutil/trace.go:171","msg":"trace[850252510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:591; }","duration":"910.716927ms","start":"2026-05-25T22:03:45.28363Z","end":"2026-05-25T22:03:46.194347Z","steps":["trace[850252510] 'agreement among raft nodes before linearized reading' (duration: 910.491424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:46.194367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:45.283606Z","time spent":"910.757177ms","remote":"127.0.0.1:54850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-25T22:03:46.194635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:44.356852Z","time spent":"1.837745783s","remote":"127.0.0.1:54798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T22:03:46.660899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.902609ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T22:03:46.660997Z","caller":"traceutil/trace.go:171","msg":"trace[1041169433] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"463.746447ms","start":"2026-05-25T22:03:46.197234Z","end":"2026-05-25T22:03:46.66098Z","steps":["trace[1041169433] 'process raft request' (duration: 311.684356ms)","trace[1041169433] 'compare' (duration: 151.799078ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:03:46.661053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:46.197222Z","time spent":"463.805029ms","remote":"127.0.0.1:54850","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-05-25T22:03:46.896393Z","caller":"traceutil/trace.go:171","msg":"trace[67796110] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"695.974406ms","start":"2026-05-25T22:03:46.20039Z","end":"2026-05-25T22:03:46.896365Z","steps":["trace[67796110] 'process raft request' (duration: 695.867395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:46.897261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:46.200381Z","time spent":"696.802565ms","remote":"127.0.0.1:54850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:03:46.897429Z","caller":"traceutil/trace.go:171","msg":"trace[787892419] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"697.490714ms","start":"2026-05-25T22:03:46.199349Z","end":"2026-05-25T22:03:46.89684Z","steps":["trace[787892419] 'process raft request' (duration: 655.851717ms)","trace[787892419] 'compare' (duration: 40.937558ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:03:46.8978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:46.199332Z","time spent":"698.264743ms","remote":"127.0.0.1:54850","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-05-25T22:03:46.898742Z","caller":"traceutil/trace.go:171","msg":"trace[967544327] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:614; }","duration":"695.295357ms","start":"2026-05-25T22:03:46.203403Z","end":"2026-05-25T22:03:46.898698Z","steps":["trace[967544327] 'read index received' (duration: 305.50251ms)","trace[967544327] 'applied index is now lower than readState.Index' (duration: 389.791897ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:03:46.89887Z","caller":"traceutil/trace.go:171","msg":"trace[2080588342] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"698.204063ms","start":"2026-05-25T22:03:46.200646Z","end":"2026-05-25T22:03:46.89885Z","steps":["trace[2080588342] 'process raft request' (duration: 695.658152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:46.898929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:46.200638Z","time spent":"698.253053ms","remote":"127.0.0.1:54850","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-05-25T22:03:46.899181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.427636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T22:03:46.899267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.858264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:03:46.899313Z","caller":"traceutil/trace.go:171","msg":"trace[1264111794] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"695.892605ms","start":"2026-05-25T22:03:46.203399Z","end":"2026-05-25T22:03:46.899291Z","steps":["trace[1264111794] 'agreement among raft nodes before linearized reading' (duration: 695.833094ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:46.899318Z","caller":"traceutil/trace.go:171","msg":"trace[388915836] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:595; }","duration":"693.577118ms","start":"2026-05-25T22:03:46.205698Z","end":"2026-05-25T22:03:46.899275Z","steps":["trace[388915836] 'agreement among raft nodes before linearized reading' (duration: 693.390985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:46.899342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:46.203375Z","time spent":"695.961026ms","remote":"127.0.0.1:54594","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-05-25T22:03:46.899383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:03:46.20569Z","time spent":"693.667758ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":0,"response size":28,"request content":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-05-25T22:03:47.497532Z","caller":"traceutil/trace.go:171","msg":"trace[48869838] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"186.924707ms","start":"2026-05-25T22:03:47.31056Z","end":"2026-05-25T22:03:47.497485Z","steps":["trace[48869838] 'process raft request' (duration: 186.079527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:48.006741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.297938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:03:48.006827Z","caller":"traceutil/trace.go:171","msg":"trace[1929760281] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"138.422239ms","start":"2026-05-25T22:03:47.868385Z","end":"2026-05-25T22:03:48.006807Z","steps":["trace[1929760281] 'range keys from in-memory index tree' (duration: 138.212096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:49.017723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.804498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:03:49.017804Z","caller":"traceutil/trace.go:171","msg":"trace[545790982] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"149.03144ms","start":"2026-05-25T22:03:48.868755Z","end":"2026-05-25T22:03:49.017787Z","steps":["trace[545790982] 'range keys from in-memory index tree' (duration: 148.734137ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:49.018555Z","caller":"traceutil/trace.go:171","msg":"trace[1323586134] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"118.203507ms","start":"2026-05-25T22:03:48.900334Z","end":"2026-05-25T22:03:49.018538Z","steps":["trace[1323586134] 'process raft request' (duration: 117.994054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:49.018971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.017723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-25T22:03:49.019008Z","caller":"traceutil/trace.go:171","msg":"trace[1536255043] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:631; }","duration":"117.088504ms","start":"2026-05-25T22:03:48.901911Z","end":"2026-05-25T22:03:49.018999Z","steps":["trace[1536255043] 'agreement among raft nodes before linearized reading' (duration: 116.937872ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:49.018618Z","caller":"traceutil/trace.go:171","msg":"trace[1615264440] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"116.584968ms","start":"2026-05-25T22:03:48.901937Z","end":"2026-05-25T22:03:49.018522Z","steps":["trace[1615264440] 'read index received' (duration: 116.528207ms)","trace[1615264440] 'applied index is now lower than readState.Index' (duration: 54.26µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:03:49.030713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.877867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-25T22:03:49.030763Z","caller":"traceutil/trace.go:171","msg":"trace[1090321756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"124.950228ms","start":"2026-05-25T22:03:48.905799Z","end":"2026-05-25T22:03:49.030749Z","steps":["trace[1090321756] 'agreement among raft nodes before linearized reading' (duration: 124.861997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:49.030916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.963906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-25T22:03:49.031002Z","caller":"traceutil/trace.go:171","msg":"trace[196065034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"124.060937ms","start":"2026-05-25T22:03:48.906925Z","end":"2026-05-25T22:03:49.030986Z","steps":["trace[196065034] 'agreement among raft nodes before linearized reading' (duration: 123.909145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:50.085781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.402901ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-25T22:03:50.086068Z","caller":"traceutil/trace.go:171","msg":"trace[1518004188] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"184.068383ms","start":"2026-05-25T22:03:49.901974Z","end":"2026-05-25T22:03:50.086042Z","steps":["trace[1518004188] 'process raft request' (duration: 184.001612ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:50.086083Z","caller":"traceutil/trace.go:171","msg":"trace[1515495475] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:676; }","duration":"208.888941ms","start":"2026-05-25T22:03:49.877162Z","end":"2026-05-25T22:03:50.086051Z","steps":["trace[1515495475] 'read index received' (duration: 76.238227ms)","trace[1515495475] 'applied index is now lower than readState.Index' (duration: 132.647924ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:03:50.086207Z","caller":"traceutil/trace.go:171","msg":"trace[2026398063] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"253.602049ms","start":"2026-05-25T22:03:49.83258Z","end":"2026-05-25T22:03:50.086182Z","steps":["trace[2026398063] 'process raft request' (duration: 120.735853ms)","trace[2026398063] 'compare' (duration: 132.277189ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:03:50.086365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.155604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:03:50.086443Z","caller":"traceutil/trace.go:171","msg":"trace[1321609640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"209.268785ms","start":"2026-05-25T22:03:49.877128Z","end":"2026-05-25T22:03:50.086397Z","steps":["trace[1321609640] 'agreement among raft nodes before linearized reading' (duration: 209.069482ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:50.174964Z","caller":"traceutil/trace.go:171","msg":"trace[1206005363] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"171.562257ms","start":"2026-05-25T22:03:50.003377Z","end":"2026-05-25T22:03:50.174939Z","steps":["trace[1206005363] 'process raft request' (duration: 170.870939ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:51.938577Z","caller":"traceutil/trace.go:171","msg":"trace[1855557675] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"190.455695ms","start":"2026-05-25T22:03:51.748099Z","end":"2026-05-25T22:03:51.938554Z","steps":["trace[1855557675] 'process raft request' (duration: 190.285173ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:57.178181Z","caller":"traceutil/trace.go:171","msg":"trace[57154068] linearizableReadLoop","detail":"{readStateIndex:752; appliedIndex:751; }","duration":"100.551516ms","start":"2026-05-25T22:03:57.077602Z","end":"2026-05-25T22:03:57.178153Z","steps":["trace[57154068] 'read index received' (duration: 54.016522ms)","trace[57154068] 'applied index is now lower than readState.Index' (duration: 46.533564ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:03:57.178213Z","caller":"traceutil/trace.go:171","msg":"trace[1211585175] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"104.552002ms","start":"2026-05-25T22:03:57.073629Z","end":"2026-05-25T22:03:57.178181Z","steps":["trace[1211585175] 'process raft request' (duration: 57.889737ms)","trace[1211585175] 'compare' (duration: 46.501873ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:03:57.178457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.838849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-25T22:03:57.179346Z","caller":"traceutil/trace.go:171","msg":"trace[484504359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:726; }","duration":"101.75845ms","start":"2026-05-25T22:03:57.077565Z","end":"2026-05-25T22:03:57.179323Z","steps":["trace[484504359] 'agreement among raft nodes before linearized reading' (duration: 100.759608ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:57.188629Z","caller":"traceutil/trace.go:171","msg":"trace[513360603] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"110.510142ms","start":"2026-05-25T22:03:57.078109Z","end":"2026-05-25T22:03:57.188619Z","steps":["trace[513360603] 'process raft request' (duration: 110.440361ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:57.395313Z","caller":"traceutil/trace.go:171","msg":"trace[1341942540] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"127.848904ms","start":"2026-05-25T22:03:57.267436Z","end":"2026-05-25T22:03:57.395285Z","steps":["trace[1341942540] 'process raft request' (duration: 127.735022ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:57.479592Z","caller":"traceutil/trace.go:171","msg":"trace[836911648] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"150.659131ms","start":"2026-05-25T22:03:57.328904Z","end":"2026-05-25T22:03:57.479563Z","steps":["trace[836911648] 'process raft request' (duration: 131.79294ms)","trace[836911648] 'compare' (duration: 18.749509ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:03:59.348113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.135147ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T22:03:59.348224Z","caller":"traceutil/trace.go:171","msg":"trace[961061652] linearizableReadLoop","detail":"{readStateIndex:772; appliedIndex:771; }","duration":"135.330001ms","start":"2026-05-25T22:03:59.212875Z","end":"2026-05-25T22:03:59.348205Z","steps":["trace[961061652] 'read index received' (duration: 32.66µs)","trace[961061652] 'applied index is now lower than readState.Index' (duration: 135.296161ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:03:59.348235Z","caller":"traceutil/trace.go:171","msg":"trace[2086939092] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"161.729938ms","start":"2026-05-25T22:03:59.186484Z","end":"2026-05-25T22:03:59.348214Z","steps":["trace[2086939092] 'process raft request' (duration: 21.394049ms)","trace[2086939092] 'compare' (duration: 140.012376ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:03:59.348416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.542713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-25T22:03:59.348451Z","caller":"traceutil/trace.go:171","msg":"trace[1398665270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:745; }","duration":"135.598724ms","start":"2026-05-25T22:03:59.212841Z","end":"2026-05-25T22:03:59.34844Z","steps":["trace[1398665270] 'agreement among raft nodes before linearized reading' (duration: 135.467712ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:03:59.348464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.294296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:03:59.348536Z","caller":"traceutil/trace.go:171","msg":"trace[1289399199] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:745; }","duration":"126.373576ms","start":"2026-05-25T22:03:59.222138Z","end":"2026-05-25T22:03:59.348512Z","steps":["trace[1289399199] 'agreement among raft nodes before linearized reading' (duration: 126.258705ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:03:59.484911Z","caller":"traceutil/trace.go:171","msg":"trace[1956170121] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"132.610209ms","start":"2026-05-25T22:03:59.352281Z","end":"2026-05-25T22:03:59.484892Z","steps":["trace[1956170121] 'process raft request' (duration: 132.408766ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:04:03.723858Z","caller":"traceutil/trace.go:171","msg":"trace[1701629797] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:792; }","duration":"349.346991ms","start":"2026-05-25T22:04:03.374486Z","end":"2026-05-25T22:04:03.723833Z","steps":["trace[1701629797] 'read index received' (duration: 349.080239ms)","trace[1701629797] 'applied index is now lower than readState.Index' (duration: 265.642µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:04:03.723942Z","caller":"traceutil/trace.go:171","msg":"trace[356563669] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"556.248602ms","start":"2026-05-25T22:04:03.16764Z","end":"2026-05-25T22:04:03.723888Z","steps":["trace[356563669] 'process raft request' (duration: 555.990989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:04:03.724311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.795136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-25T22:04:03.724404Z","caller":"traceutil/trace.go:171","msg":"trace[1309028351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:765; }","duration":"349.944188ms","start":"2026-05-25T22:04:03.374438Z","end":"2026-05-25T22:04:03.724382Z","steps":["trace[1309028351] 'agreement among raft nodes before linearized reading' (duration: 349.578293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:04:03.724453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:04:03.374419Z","time spent":"350.02568ms","remote":"127.0.0.1:54850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-25T22:04:03.724461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:04:03.167621Z","time spent":"556.682186ms","remote":"127.0.0.1:54850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T22:04:03.724734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.914324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-25T22:04:03.724918Z","caller":"traceutil/trace.go:171","msg":"trace[689350660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:765; }","duration":"209.165226ms","start":"2026-05-25T22:04:03.515727Z","end":"2026-05-25T22:04:03.724892Z","steps":["trace[689350660] 'agreement among raft nodes before linearized reading' (duration: 208.478197ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:04:03.922466Z","caller":"traceutil/trace.go:171","msg":"trace[2047481295] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"193.025532ms","start":"2026-05-25T22:04:03.729409Z","end":"2026-05-25T22:04:03.922435Z","steps":["trace[2047481295] 'process raft request' (duration: 192.757289ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:04:03.9471Z","caller":"traceutil/trace.go:171","msg":"trace[1930571414] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"217.507642ms","start":"2026-05-25T22:04:03.729537Z","end":"2026-05-25T22:04:03.947045Z","steps":["trace[1930571414] 'process raft request' (duration: 217.322119ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:04:03.947387Z","caller":"traceutil/trace.go:171","msg":"trace[1141308263] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"131.639988ms","start":"2026-05-25T22:04:03.815696Z","end":"2026-05-25T22:04:03.947336Z","steps":["trace[1141308263] 'process raft request' (duration: 131.281604ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:04:04.650423Z","caller":"traceutil/trace.go:171","msg":"trace[917529747] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"211.00617ms","start":"2026-05-25T22:04:04.439377Z","end":"2026-05-25T22:04:04.650383Z","steps":["trace[917529747] 'process raft request' (duration: 209.637424ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:04:07.948639Z","caller":"traceutil/trace.go:171","msg":"trace[1810061215] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"102.008824ms","start":"2026-05-25T22:04:07.846606Z","end":"2026-05-25T22:04:07.948615Z","steps":["trace[1810061215] 'process raft request' (duration: 101.649109ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:04:08.233186Z","caller":"traceutil/trace.go:171","msg":"trace[1598934491] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:822; }","duration":"108.725887ms","start":"2026-05-25T22:04:08.124431Z","end":"2026-05-25T22:04:08.233157Z","steps":["trace[1598934491] 'read index received' (duration: 93.354043ms)","trace[1598934491] 'applied index is now lower than readState.Index' (duration: 15.370794ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:04:08.233232Z","caller":"traceutil/trace.go:171","msg":"trace[219758462] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"120.839522ms","start":"2026-05-25T22:04:08.112371Z","end":"2026-05-25T22:04:08.23321Z","steps":["trace[219758462] 'process raft request' (duration: 120.738931ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:04:08.233232Z","caller":"traceutil/trace.go:171","msg":"trace[527921489] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"120.958405ms","start":"2026-05-25T22:04:08.112224Z","end":"2026-05-25T22:04:08.233183Z","steps":["trace[527921489] 'process raft request' (duration: 105.684653ms)","trace[527921489] 'compare' (duration: 14.969079ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:04:08.233361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.853846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:04:08.233408Z","caller":"traceutil/trace.go:171","msg":"trace[212255960] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:794; }","duration":"146.975787ms","start":"2026-05-25T22:04:08.086421Z","end":"2026-05-25T22:04:08.233397Z","steps":["trace[212255960] 'agreement among raft nodes before linearized reading' (duration: 146.850896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:04:08.233604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.833166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:04:08.233758Z","caller":"traceutil/trace.go:171","msg":"trace[1198483126] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:794; }","duration":"147.063779ms","start":"2026-05-25T22:04:08.086667Z","end":"2026-05-25T22:04:08.233731Z","steps":["trace[1198483126] 'agreement among raft nodes before linearized reading' (duration: 146.794676ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:04:08.604056Z","caller":"traceutil/trace.go:171","msg":"trace[1772775002] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:842; }","duration":"117.595436ms","start":"2026-05-25T22:04:08.486432Z","end":"2026-05-25T22:04:08.604028Z","steps":["trace[1772775002] 'read index received' (duration: 76.608983ms)","trace[1772775002] 'applied index is now lower than readState.Index' (duration: 40.985483ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:04:08.604146Z","caller":"traceutil/trace.go:171","msg":"trace[1382269852] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"129.588112ms","start":"2026-05-25T22:04:08.474523Z","end":"2026-05-25T22:04:08.604111Z","steps":["trace[1382269852] 'process raft request' (duration: 88.537688ms)","trace[1382269852] 'compare' (duration: 40.846692ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:04:08.604255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.790499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:04:08.604288Z","caller":"traceutil/trace.go:171","msg":"trace[1526897296] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:813; }","duration":"117.89695ms","start":"2026-05-25T22:04:08.486382Z","end":"2026-05-25T22:04:08.604279Z","steps":["trace[1526897296] 'agreement among raft nodes before linearized reading' (duration: 117.796969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:04:08.604767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.301024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:04:08.604798Z","caller":"traceutil/trace.go:171","msg":"trace[1649817866] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:813; }","duration":"118.336794ms","start":"2026-05-25T22:04:08.486453Z","end":"2026-05-25T22:04:08.60479Z","steps":["trace[1649817866] 'agreement among raft nodes before linearized reading' (duration: 118.286153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:04:08.60482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.238991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:04:08.604884Z","caller":"traceutil/trace.go:171","msg":"trace[1281105386] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:813; }","duration":"101.340203ms","start":"2026-05-25T22:04:08.503528Z","end":"2026-05-25T22:04:08.604868Z","steps":["trace[1281105386] 'agreement among raft nodes before linearized reading' (duration: 101.245222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:04:08.60506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.698686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:04:08.605089Z","caller":"traceutil/trace.go:171","msg":"trace[1237965182] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:813; }","duration":"101.758727ms","start":"2026-05-25T22:04:08.503323Z","end":"2026-05-25T22:04:08.605082Z","steps":["trace[1237965182] 'agreement among raft nodes before linearized reading' (duration: 101.714157ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:04:08.605125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.983968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:04:08.605186Z","caller":"traceutil/trace.go:171","msg":"trace[1488300661] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:813; }","duration":"101.05663ms","start":"2026-05-25T22:04:08.504111Z","end":"2026-05-25T22:04:08.605168Z","steps":["trace[1488300661] 'agreement among raft nodes before linearized reading' (duration: 100.968848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:04:08.605304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.937451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:04:08.605336Z","caller":"traceutil/trace.go:171","msg":"trace[125225619] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:813; }","duration":"118.006172ms","start":"2026-05-25T22:04:08.487322Z","end":"2026-05-25T22:04:08.605328Z","steps":["trace[125225619] 'agreement among raft nodes before linearized reading' (duration: 117.958721ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:04:14.87564Z","caller":"traceutil/trace.go:171","msg":"trace[1221129113] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:917; }","duration":"127.324801ms","start":"2026-05-25T22:04:14.748292Z","end":"2026-05-25T22:04:14.875616Z","steps":["trace[1221129113] 'read index received' (duration: 127.160029ms)","trace[1221129113] 'applied index is now lower than readState.Index' (duration: 164.082µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:04:14.875762Z","caller":"traceutil/trace.go:171","msg":"trace[11342760] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"136.994108ms","start":"2026-05-25T22:04:14.738729Z","end":"2026-05-25T22:04:14.875723Z","steps":["trace[11342760] 'process raft request' (duration: 136.767826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:04:14.875798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.481342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:04:14.875951Z","caller":"traceutil/trace.go:171","msg":"trace[1798270625] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:887; }","duration":"127.692415ms","start":"2026-05-25T22:04:14.748246Z","end":"2026-05-25T22:04:14.875938Z","steps":["trace[1798270625] 'agreement among raft nodes before linearized reading' (duration: 127.503543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:04:15.35067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.539173ms","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-05-25T22:04:15.350837Z","caller":"traceutil/trace.go:171","msg":"trace[2084636772] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:888; }","duration":"341.722605ms","start":"2026-05-25T22:04:15.009094Z","end":"2026-05-25T22:04:15.350817Z","steps":["trace[2084636772] 'range keys from in-memory index tree' (duration: 341.520373ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:04:15.962728Z","caller":"traceutil/trace.go:171","msg":"trace[1001343166] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"172.691608ms","start":"2026-05-25T22:04:15.789993Z","end":"2026-05-25T22:04:15.962685Z","steps":["trace[1001343166] 'process raft request' (duration: 172.405725ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:04:16.187667Z","caller":"traceutil/trace.go:171","msg":"trace[1558918716] linearizableReadLoop","detail":"{readStateIndex:922; appliedIndex:921; }","duration":"162.832234ms","start":"2026-05-25T22:04:16.024804Z","end":"2026-05-25T22:04:16.187636Z","steps":["trace[1558918716] 'read index received' (duration: 89.439306ms)","trace[1558918716] 'applied index is now lower than readState.Index' (duration: 73.391888ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:04:16.187933Z","caller":"traceutil/trace.go:171","msg":"trace[900775033] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"201.257908ms","start":"2026-05-25T22:04:15.986659Z","end":"2026-05-25T22:04:16.187917Z","steps":["trace[900775033] 'process raft request' (duration: 127.546917ms)","trace[900775033] 'compare' (duration: 73.302507ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:04:16.188234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.407411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-25T22:04:16.188395Z","caller":"traceutil/trace.go:171","msg":"trace[1001032030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:891; }","duration":"163.604993ms","start":"2026-05-25T22:04:16.024765Z","end":"2026-05-25T22:04:16.18837Z","steps":["trace[1001032030] 'agreement among raft nodes before linearized reading' (duration: 163.176147ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:04:16.434227Z","caller":"traceutil/trace.go:171","msg":"trace[1016848256] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"242.882306ms","start":"2026-05-25T22:04:16.191315Z","end":"2026-05-25T22:04:16.434198Z","steps":["trace[1016848256] 'process raft request' (duration: 237.133463ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:04:16.527566Z","caller":"traceutil/trace.go:171","msg":"trace[66612852] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"335.279445ms","start":"2026-05-25T22:04:16.192242Z","end":"2026-05-25T22:04:16.527521Z","steps":["trace[66612852] 'process raft request' (duration: 335.021352ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:04:16.527676Z","caller":"traceutil/trace.go:171","msg":"trace[762168251] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"335.204003ms","start":"2026-05-25T22:04:16.192441Z","end":"2026-05-25T22:04:16.527645Z","steps":["trace[762168251] 'process raft request' (duration: 334.967671ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:04:16.527695Z","caller":"traceutil/trace.go:171","msg":"trace[1794403127] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"329.560311ms","start":"2026-05-25T22:04:16.198117Z","end":"2026-05-25T22:04:16.527678Z","steps":["trace[1794403127] 'process raft request' (duration: 329.39469ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:04:16.527751Z","caller":"traceutil/trace.go:171","msg":"trace[635258768] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"335.018391ms","start":"2026-05-25T22:04:16.192719Z","end":"2026-05-25T22:04:16.527737Z","steps":["trace[635258768] 'process raft request' (duration: 334.736798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:04:16.527763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:04:16.192218Z","time spent":"335.446987ms","remote":"127.0.0.1:54850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T22:04:16.527823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:04:16.192704Z","time spent":"335.085772ms","remote":"127.0.0.1:54850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T22:04:16.52782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:04:16.192433Z","time spent":"335.313854ms","remote":"127.0.0.1:54798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T22:04:16.527821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:04:16.198109Z","time spent":"329.672033ms","remote":"127.0.0.1:54692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12761,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T22:04:37.154087Z","caller":"traceutil/trace.go:171","msg":"trace[1146979261] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1039; }","duration":"100.273742ms","start":"2026-05-25T22:04:37.053787Z","end":"2026-05-25T22:04:37.154061Z","steps":["trace[1146979261] 'read index received' (duration: 98.440703ms)","trace[1146979261] 'applied index is now lower than readState.Index' (duration: 1.831819ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:04:37.154235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.423593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"info","ts":"2026-05-25T22:04:37.154188Z","caller":"traceutil/trace.go:171","msg":"trace[1724847221] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"100.473264ms","start":"2026-05-25T22:04:37.053685Z","end":"2026-05-25T22:04:37.154159Z","steps":["trace[1724847221] 'process raft request' (duration: 98.589414ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:04:37.1543Z","caller":"traceutil/trace.go:171","msg":"trace[633841262] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:1004; }","duration":"100.520484ms","start":"2026-05-25T22:04:37.053744Z","end":"2026-05-25T22:04:37.154264Z","steps":["trace[633841262] 'agreement among raft nodes before linearized reading' (duration: 100.423563ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:04:37.311715Z","caller":"traceutil/trace.go:171","msg":"trace[2006778241] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"111.608669ms","start":"2026-05-25T22:04:37.200067Z","end":"2026-05-25T22:04:37.311676Z","steps":["trace[2006778241] 'process raft request' (duration: 111.384927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:04:47.87457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.816659ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-25T22:04:47.878843Z","caller":"traceutil/trace.go:171","msg":"trace[249030630] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"317.544934ms","start":"2026-05-25T22:04:47.561282Z","end":"2026-05-25T22:04:47.878827Z","steps":["trace[249030630] 'process raft request' (duration: 317.433073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:04:47.878942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:04:47.561265Z","time spent":"317.619905ms","remote":"127.0.0.1:54850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:06:00.796796Z","caller":"traceutil/trace.go:171","msg":"trace[1105214584] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"499.057211ms","start":"2026-05-25T22:06:00.297712Z","end":"2026-05-25T22:06:00.796769Z","steps":["trace[1105214584] 'process raft request' (duration: 498.830536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:06:00.796954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:06:00.29769Z","time spent":"499.184404ms","remote":"127.0.0.1:54850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:06:23.553337Z","caller":"traceutil/trace.go:171","msg":"trace[830439129] linearizableReadLoop","detail":"{readStateIndex:1457; appliedIndex:1456; }","duration":"113.59507ms","start":"2026-05-25T22:06:23.43972Z","end":"2026-05-25T22:06:23.553315Z","steps":["trace[830439129] 'read index received' (duration: 113.412467ms)","trace[830439129] 'applied index is now lower than readState.Index' (duration: 181.503µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:06:23.553445Z","caller":"traceutil/trace.go:171","msg":"trace[432983363] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"115.14286ms","start":"2026-05-25T22:06:23.438261Z","end":"2026-05-25T22:06:23.553404Z","steps":["trace[432983363] 'process raft request' (duration: 114.929576ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:06:23.553781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.915687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/\" range_end:\"/registry/rolebindings/kube-system0\" limit:500 ","response":"range_response_count:11 size:7467"} {"level":"info","ts":"2026-05-25T22:06:23.553846Z","caller":"traceutil/trace.go:171","msg":"trace[1695540258] range","detail":"{range_begin:/registry/rolebindings/kube-system/; range_end:/registry/rolebindings/kube-system0; response_count:11; response_revision:1397; }","duration":"113.011009ms","start":"2026-05-25T22:06:23.440817Z","end":"2026-05-25T22:06:23.553828Z","steps":["trace[1695540258] 'agreement among raft nodes before linearized reading' (duration: 112.701613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:06:23.553838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.09952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:70 size:51822"} {"level":"info","ts":"2026-05-25T22:06:23.553927Z","caller":"traceutil/trace.go:171","msg":"trace[1059794830] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:70; response_revision:1397; }","duration":"114.194672ms","start":"2026-05-25T22:06:23.439684Z","end":"2026-05-25T22:06:23.553879Z","steps":["trace[1059794830] 'agreement among raft nodes before linearized reading' (duration: 113.775644ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:06:24.099039Z","caller":"traceutil/trace.go:171","msg":"trace[1781075721] linearizableReadLoop","detail":"{readStateIndex:1460; appliedIndex:1459; }","duration":"109.881253ms","start":"2026-05-25T22:06:23.989129Z","end":"2026-05-25T22:06:24.09901Z","steps":["trace[1781075721] 'read index received' (duration: 109.578518ms)","trace[1781075721] 'applied index is now lower than readState.Index' (duration: 301.445µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:06:24.09937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.214509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/kube-system/\" range_end:\"/registry/statefulsets/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:06:24.099385Z","caller":"traceutil/trace.go:171","msg":"trace[1165700998] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"111.670547ms","start":"2026-05-25T22:06:23.987508Z","end":"2026-05-25T22:06:24.099179Z","steps":["trace[1165700998] 'process raft request' (duration: 111.304351ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:06:24.099441Z","caller":"traceutil/trace.go:171","msg":"trace[1196267291] range","detail":"{range_begin:/registry/statefulsets/kube-system/; range_end:/registry/statefulsets/kube-system0; response_count:0; response_revision:1400; }","duration":"110.334992ms","start":"2026-05-25T22:06:23.989087Z","end":"2026-05-25T22:06:24.099422Z","steps":["trace[1196267291] 'agreement among raft nodes before linearized reading' (duration: 110.201389ms)"],"step_count":1}