{"level":"warn","ts":"2026-05-19T13:17:59.851696Z","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-19T13:17:59.851843Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.227: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.227:2380","--initial-cluster=instance=https://199.204.45.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.227: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-19T13:17:59.851938Z","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-19T13:17:59.851949Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-05-19T13:17:59.851983Z","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-19T13:17:59.854055Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"]} {"level":"info","ts":"2026-05-19T13:17:59.85417Z","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.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.227: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-19T13:18:00.06422Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"209.754968ms"} {"level":"info","ts":"2026-05-19T13:18:00.207513Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"572db18442eba1c2","cluster-id":"fb1028a037bf3cd6"} {"level":"info","ts":"2026-05-19T13:18:00.207679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T13:18:00.207756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 0"} {"level":"info","ts":"2026-05-19T13:18:00.20778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 572db18442eba1c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T13:18:00.207824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 1"} {"level":"info","ts":"2026-05-19T13:18:00.20791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"warn","ts":"2026-05-19T13:18:00.209316Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T13:18:00.21058Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T13:18:00.213843Z","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-19T13:18:00.216912Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"572db18442eba1c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T13:18:00.2171Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"572db18442eba1c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T13:18:00.21702Z","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-19T13:18:00.217273Z","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-19T13:18:00.217305Z","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-19T13:18:00.218073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"info","ts":"2026-05-19T13:18:00.219466Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","added-peer-id":"572db18442eba1c2","added-peer-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-05-19T13:18:00.234458Z","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-19T13:18:00.234673Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"572db18442eba1c2","initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T13:18:00.2347Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T13:18:00.234797Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-05-19T13:18:00.234818Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-05-19T13:18:01.108414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T13:18:01.108538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T13:18:01.1086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgPreVoteResp from 572db18442eba1c2 at term 1"} {"level":"info","ts":"2026-05-19T13:18:01.108654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became candidate at term 2"} {"level":"info","ts":"2026-05-19T13:18:01.108672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgVoteResp from 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-05-19T13:18:01.108699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became leader at term 2"} {"level":"info","ts":"2026-05-19T13:18:01.108718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 572db18442eba1c2 elected leader 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-05-19T13:18:01.109737Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T13:18:01.110647Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T13:18:01.110641Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"572db18442eba1c2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.227:2379]}","request-path":"/0/members/572db18442eba1c2/attributes","cluster-id":"fb1028a037bf3cd6","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T13:18:01.110807Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T13:18:01.110954Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T13:18:01.110976Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T13:18:01.114195Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T13:18:01.114197Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.227:2379"} {"level":"info","ts":"2026-05-19T13:18:01.117752Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T13:18:01.11823Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T13:18:01.118505Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-19T13:18:03.891072Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052784961687822,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T13:18:03.936344Z","caller":"traceutil/trace.go:171","msg":"trace[1818925862] linearizableReadLoop","detail":"{readStateIndex:114; appliedIndex:113; }","duration":"545.749702ms","start":"2026-05-19T13:18:03.390552Z","end":"2026-05-19T13:18:03.936302Z","steps":["trace[1818925862] 'read index received' (duration: 545.386363ms)","trace[1818925862] 'applied index is now lower than readState.Index' (duration: 361.709µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:18:03.936458Z","caller":"traceutil/trace.go:171","msg":"trace[87869956] transaction","detail":"{read_only:false; response_revision:109; number_of_response:1; }","duration":"547.630156ms","start":"2026-05-19T13:18:03.388788Z","end":"2026-05-19T13:18:03.936418Z","steps":["trace[87869956] 'process raft request' (duration: 547.295269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:18:03.936797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.879175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-19T13:18:03.936852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.162542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T13:18:03.936879Z","caller":"traceutil/trace.go:171","msg":"trace[1639721362] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:109; }","duration":"446.159713ms","start":"2026-05-19T13:18:03.490701Z","end":"2026-05-19T13:18:03.936861Z","steps":["trace[1639721362] 'agreement among raft nodes before linearized reading' (duration: 445.846905ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:18:03.937086Z","caller":"traceutil/trace.go:171","msg":"trace[1255601704] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:109; }","duration":"546.507ms","start":"2026-05-19T13:18:03.390528Z","end":"2026-05-19T13:18:03.937035Z","steps":["trace[1255601704] 'agreement among raft nodes before linearized reading' (duration: 545.944557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:18:03.937145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:18:03.390506Z","time spent":"546.626362ms","remote":"127.0.0.1:35898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"warn","ts":"2026-05-19T13:18:03.937266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:18:03.388761Z","time spent":"547.805121ms","remote":"127.0.0.1:35902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T13:18:03.937234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:18:03.490676Z","time spent":"446.416838ms","remote":"127.0.0.1:35520","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-19T13:18:04.303758Z","caller":"traceutil/trace.go:171","msg":"trace[495106856] transaction","detail":"{read_only:false; response_revision:111; number_of_response:1; }","duration":"288.562035ms","start":"2026-05-19T13:18:04.015158Z","end":"2026-05-19T13:18:04.30372Z","steps":["trace[495106856] 'process raft request' (duration: 285.221116ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:18:04.66046Z","caller":"traceutil/trace.go:171","msg":"trace[811587290] linearizableReadLoop","detail":"{readStateIndex:117; appliedIndex:116; }","duration":"168.143509ms","start":"2026-05-19T13:18:04.492292Z","end":"2026-05-19T13:18:04.660436Z","steps":["trace[811587290] 'read index received' (duration: 165.281511ms)","trace[811587290] 'applied index is now lower than readState.Index' (duration: 2.860658ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:18:04.660587Z","caller":"traceutil/trace.go:171","msg":"trace[2069659744] transaction","detail":"{read_only:false; response_revision:112; number_of_response:1; }","duration":"350.99782ms","start":"2026-05-19T13:18:04.309532Z","end":"2026-05-19T13:18:04.66053Z","steps":["trace[2069659744] 'process raft request' (duration: 348.099891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:18:04.660683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.379774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-19T13:18:04.660903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:18:04.309511Z","time spent":"351.248125ms","remote":"127.0.0.1:35898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":634,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T13:18:04.66083Z","caller":"traceutil/trace.go:171","msg":"trace[901263202] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:112; }","duration":"168.588059ms","start":"2026-05-19T13:18:04.49223Z","end":"2026-05-19T13:18:04.660818Z","steps":["trace[901263202] 'agreement among raft nodes before linearized reading' (duration: 168.338103ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:18:05.417798Z","caller":"traceutil/trace.go:171","msg":"trace[1501240483] transaction","detail":"{read_only:false; response_revision:216; number_of_response:1; }","duration":"188.806251ms","start":"2026-05-19T13:18:05.228969Z","end":"2026-05-19T13:18:05.417776Z","steps":["trace[1501240483] 'process raft request' (duration: 186.154938ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:18:31.310992Z","caller":"traceutil/trace.go:171","msg":"trace[967907620] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"266.226209ms","start":"2026-05-19T13:18:31.044712Z","end":"2026-05-19T13:18:31.310938Z","steps":["trace[967907620] 'process raft request' (duration: 266.118017ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:18:31.465757Z","caller":"traceutil/trace.go:171","msg":"trace[894900933] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"295.508269ms","start":"2026-05-19T13:18:31.170224Z","end":"2026-05-19T13:18:31.465732Z","steps":["trace[894900933] 'process raft request' (duration: 295.1352ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:18:31.465874Z","caller":"traceutil/trace.go:171","msg":"trace[1528924691] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"266.071667ms","start":"2026-05-19T13:18:31.199768Z","end":"2026-05-19T13:18:31.46584Z","steps":["trace[1528924691] 'process raft request' (duration: 265.978985ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:18:31.465894Z","caller":"traceutil/trace.go:171","msg":"trace[517026749] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"294.010553ms","start":"2026-05-19T13:18:31.171873Z","end":"2026-05-19T13:18:31.465884Z","steps":["trace[517026749] 'process raft request' (duration: 293.561793ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:18:31.465897Z","caller":"traceutil/trace.go:171","msg":"trace[1613195682] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"304.150231ms","start":"2026-05-19T13:18:31.161691Z","end":"2026-05-19T13:18:31.465841Z","steps":["trace[1613195682] 'process raft request' (duration: 303.530587ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:18:31.466043Z","caller":"traceutil/trace.go:171","msg":"trace[1145353877] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"290.176484ms","start":"2026-05-19T13:18:31.175852Z","end":"2026-05-19T13:18:31.466029Z","steps":["trace[1145353877] 'process raft request' (duration: 289.652051ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:18:31.466052Z","caller":"traceutil/trace.go:171","msg":"trace[539143511] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"312.242992ms","start":"2026-05-19T13:18:31.153797Z","end":"2026-05-19T13:18:31.46604Z","steps":["trace[539143511] 'process raft request' (duration: 311.266609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:18:31.466076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:18:31.161673Z","time spent":"304.320195ms","remote":"127.0.0.1:35564","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-19T13:18:31.466063Z","caller":"traceutil/trace.go:171","msg":"trace[1029717740] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"300.263999ms","start":"2026-05-19T13:18:31.165781Z","end":"2026-05-19T13:18:31.466045Z","steps":["trace[1029717740] 'process raft request' (duration: 299.478581ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:18:31.46616Z","caller":"traceutil/trace.go:171","msg":"trace[1767699382] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"284.306765ms","start":"2026-05-19T13:18:31.18184Z","end":"2026-05-19T13:18:31.466147Z","steps":["trace[1767699382] 'process raft request' (duration: 283.703991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:18:31.466175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:18:31.153775Z","time spent":"312.314943ms","remote":"127.0.0.1:35564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T13:18:31.466245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:18:31.16576Z","time spent":"300.399013ms","remote":"127.0.0.1:35564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T13:18:31.466292Z","caller":"traceutil/trace.go:171","msg":"trace[8114168] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"282.994504ms","start":"2026-05-19T13:18:31.183285Z","end":"2026-05-19T13:18:31.46628Z","steps":["trace[8114168] 'process raft request' (duration: 282.314129ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:18:31.465798Z","caller":"traceutil/trace.go:171","msg":"trace[987501761] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"291.459373ms","start":"2026-05-19T13:18:31.17431Z","end":"2026-05-19T13:18:31.465769Z","steps":["trace[987501761] 'process raft request' (duration: 291.159936ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:18:31.465859Z","caller":"traceutil/trace.go:171","msg":"trace[1754281477] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"295.418516ms","start":"2026-05-19T13:18:31.170427Z","end":"2026-05-19T13:18:31.465845Z","steps":["trace[1754281477] 'process raft request' (duration: 294.976126ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:18:31.47534Z","caller":"traceutil/trace.go:171","msg":"trace[129567382] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"154.542968ms","start":"2026-05-19T13:18:31.320771Z","end":"2026-05-19T13:18:31.475313Z","steps":["trace[129567382] 'process raft request' (duration: 154.393945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:18:32.090081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.110648ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T13:18:32.090199Z","caller":"traceutil/trace.go:171","msg":"trace[361461493] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"201.798867ms","start":"2026-05-19T13:18:31.888377Z","end":"2026-05-19T13:18:32.090176Z","steps":["trace[361461493] 'process raft request' (duration: 73.29492ms)","trace[361461493] 'compare' (duration: 127.961684ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:18:32.096794Z","caller":"traceutil/trace.go:171","msg":"trace[1483313157] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:582; }","duration":"177.858215ms","start":"2026-05-19T13:18:31.918898Z","end":"2026-05-19T13:18:32.096757Z","steps":["trace[1483313157] 'read index received' (duration: 42.758624ms)","trace[1483313157] 'applied index is now lower than readState.Index' (duration: 135.098651ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:18:32.096975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.06576ms","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-19T13:18:32.097Z","caller":"traceutil/trace.go:171","msg":"trace[1761428030] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:562; }","duration":"178.135802ms","start":"2026-05-19T13:18:31.918857Z","end":"2026-05-19T13:18:32.096993Z","steps":["trace[1761428030] 'agreement among raft nodes before linearized reading' (duration: 177.998698ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:18:32.09813Z","caller":"traceutil/trace.go:171","msg":"trace[1564841449] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"208.741501ms","start":"2026-05-19T13:18:31.889377Z","end":"2026-05-19T13:18:32.098118Z","steps":["trace[1564841449] 'process raft request' (duration: 206.443596ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:18:32.292975Z","caller":"traceutil/trace.go:171","msg":"trace[709892719] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"174.629129ms","start":"2026-05-19T13:18:32.11832Z","end":"2026-05-19T13:18:32.292949Z","steps":["trace[709892719] 'process raft request' (duration: 82.472036ms)","trace[709892719] 'compare' (duration: 92.0362ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:18:32.297012Z","caller":"traceutil/trace.go:171","msg":"trace[1937574626] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"173.33999ms","start":"2026-05-19T13:18:32.123659Z","end":"2026-05-19T13:18:32.296999Z","steps":["trace[1937574626] 'process raft request' (duration: 173.187815ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:18:32.298296Z","caller":"traceutil/trace.go:171","msg":"trace[186194925] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"141.80664ms","start":"2026-05-19T13:18:32.156477Z","end":"2026-05-19T13:18:32.298284Z","steps":["trace[186194925] 'process raft request' (duration: 141.098823ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:18:32.493306Z","caller":"traceutil/trace.go:171","msg":"trace[316995951] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"168.23948ms","start":"2026-05-19T13:18:32.325011Z","end":"2026-05-19T13:18:32.49325Z","steps":["trace[316995951] 'process raft request' (duration: 124.557834ms)","trace[316995951] 'compare' (duration: 43.384898ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:18:32.493381Z","caller":"traceutil/trace.go:171","msg":"trace[1277035333] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"120.217092ms","start":"2026-05-19T13:18:32.373083Z","end":"2026-05-19T13:18:32.4933Z","steps":["trace[1277035333] 'process raft request' (duration: 120.056418ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:18:36.328675Z","caller":"traceutil/trace.go:171","msg":"trace[23024174] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"207.523372ms","start":"2026-05-19T13:18:36.121124Z","end":"2026-05-19T13:18:36.328647Z","steps":["trace[23024174] 'read index received' (duration: 206.420806ms)","trace[23024174] 'applied index is now lower than readState.Index' (duration: 1.101676ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:18:36.328799Z","caller":"traceutil/trace.go:171","msg":"trace[385524800] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"337.095074ms","start":"2026-05-19T13:18:35.991693Z","end":"2026-05-19T13:18:36.328788Z","steps":["trace[385524800] 'process raft request' (duration: 336.006838ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:18:36.328909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:18:35.991673Z","time spent":"337.152566ms","remote":"127.0.0.1:35604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T13:18:36.32895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.828229ms","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-19T13:18:36.328977Z","caller":"traceutil/trace.go:171","msg":"trace[2074388205] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:599; }","duration":"207.890251ms","start":"2026-05-19T13:18:36.121079Z","end":"2026-05-19T13:18:36.328969Z","steps":["trace[2074388205] 'agreement among raft nodes before linearized reading' (duration: 207.796748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:18:36.66712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.471615ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T13:18:36.667303Z","caller":"traceutil/trace.go:171","msg":"trace[1479444393] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"290.195934ms","start":"2026-05-19T13:18:36.377092Z","end":"2026-05-19T13:18:36.667287Z","steps":["trace[1479444393] 'process raft request' (duration: 290.140292ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:18:36.667406Z","caller":"traceutil/trace.go:171","msg":"trace[1247220486] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"335.039076ms","start":"2026-05-19T13:18:36.332337Z","end":"2026-05-19T13:18:36.667376Z","steps":["trace[1247220486] 'process raft request' (duration: 192.228223ms)","trace[1247220486] 'compare' (duration: 142.356581ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:18:36.667547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:18:36.332329Z","time spent":"335.139189ms","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T13:18:38.012741Z","caller":"traceutil/trace.go:171","msg":"trace[1333378212] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"121.501414ms","start":"2026-05-19T13:18:37.891214Z","end":"2026-05-19T13:18:38.012715Z","steps":["trace[1333378212] 'process raft request' (duration: 121.34795ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:18:38.431395Z","caller":"traceutil/trace.go:171","msg":"trace[159424932] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"283.160718ms","start":"2026-05-19T13:18:38.148204Z","end":"2026-05-19T13:18:38.431365Z","steps":["trace[159424932] 'read index received' (duration: 281.706974ms)","trace[159424932] 'applied index is now lower than readState.Index' (duration: 1.452584ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:18:38.431494Z","caller":"traceutil/trace.go:171","msg":"trace[792703529] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"332.059955ms","start":"2026-05-19T13:18:38.099395Z","end":"2026-05-19T13:18:38.431455Z","steps":["trace[792703529] 'process raft request' (duration: 330.817016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:18:38.431632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.433034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-22ntf\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-05-19T13:18:38.431645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:18:38.099363Z","time spent":"332.195978ms","remote":"127.0.0.1:35604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T13:18:38.43167Z","caller":"traceutil/trace.go:171","msg":"trace[815626245] range","detail":"{range_begin:/registry/pods/kube-system/cilium-22ntf; range_end:; response_count:1; response_revision:614; }","duration":"283.530127ms","start":"2026-05-19T13:18:38.148127Z","end":"2026-05-19T13:18:38.431657Z","steps":["trace[815626245] 'agreement among raft nodes before linearized reading' (duration: 283.375863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:18:38.431661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.773409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:18:38.431745Z","caller":"traceutil/trace.go:171","msg":"trace[1821875912] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"187.886391ms","start":"2026-05-19T13:18:38.243832Z","end":"2026-05-19T13:18:38.431719Z","steps":["trace[1821875912] 'agreement among raft nodes before linearized reading' (duration: 187.746448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:18:39.362633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.957747ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T13:18:39.362812Z","caller":"traceutil/trace.go:171","msg":"trace[336953396] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:637; }","duration":"691.670689ms","start":"2026-05-19T13:18:38.671126Z","end":"2026-05-19T13:18:39.362797Z","steps":["trace[336953396] 'read index received' (duration: 174.552028ms)","trace[336953396] 'applied index is now lower than readState.Index' (duration: 517.117791ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:18:39.362854Z","caller":"traceutil/trace.go:171","msg":"trace[859567969] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"897.773988ms","start":"2026-05-19T13:18:38.465046Z","end":"2026-05-19T13:18:39.36282Z","steps":["trace[859567969] 'process raft request' (duration: 380.546404ms)","trace[859567969] 'compare' (duration: 516.808214ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:18:39.362968Z","caller":"traceutil/trace.go:171","msg":"trace[338933488] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"877.787558ms","start":"2026-05-19T13:18:38.48517Z","end":"2026-05-19T13:18:39.362958Z","steps":["trace[338933488] 'process raft request' (duration: 877.559843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:18:39.363005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:18:38.465025Z","time spent":"897.902031ms","remote":"127.0.0.1:35724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T13:18:39.363027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:18:38.485148Z","time spent":"877.8487ms","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T13:18:39.363144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.466905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:18:39.363223Z","caller":"traceutil/trace.go:171","msg":"trace[78816932] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"119.605028ms","start":"2026-05-19T13:18:39.243597Z","end":"2026-05-19T13:18:39.363202Z","steps":["trace[78816932] 'agreement among raft nodes before linearized reading' (duration: 119.439995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:18:39.363208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.072578ms","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-19T13:18:39.363325Z","caller":"traceutil/trace.go:171","msg":"trace[1613760127] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:616; }","duration":"692.227912ms","start":"2026-05-19T13:18:38.671077Z","end":"2026-05-19T13:18:39.363305Z","steps":["trace[1613760127] 'agreement among raft nodes before linearized reading' (duration: 691.993276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:18:39.363361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:18:38.671057Z","time spent":"692.295433ms","remote":"127.0.0.1:35808","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-19T13:18:40.444628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.882305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:18:40.444688Z","caller":"traceutil/trace.go:171","msg":"trace[365946892] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:631; }","duration":"122.988087ms","start":"2026-05-19T13:18:40.321686Z","end":"2026-05-19T13:18:40.444674Z","steps":["trace[365946892] 'range keys from in-memory index tree' (duration: 122.824074ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:18:55.649689Z","caller":"traceutil/trace.go:171","msg":"trace[1983112732] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"178.364248ms","start":"2026-05-19T13:18:55.471303Z","end":"2026-05-19T13:18:55.649667Z","steps":["trace[1983112732] 'process raft request' (duration: 178.224545ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:18:56.133258Z","caller":"traceutil/trace.go:171","msg":"trace[1588133797] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:806; }","duration":"538.491942ms","start":"2026-05-19T13:18:55.594737Z","end":"2026-05-19T13:18:56.133229Z","steps":["trace[1588133797] 'read index received' (duration: 54.872049ms)","trace[1588133797] 'applied index is now lower than readState.Index' (duration: 483.618503ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:18:56.133406Z","caller":"traceutil/trace.go:171","msg":"trace[161805477] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"659.026902ms","start":"2026-05-19T13:18:55.474369Z","end":"2026-05-19T13:18:56.133396Z","steps":["trace[161805477] 'process raft request' (duration: 650.316737ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:18:56.133509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:18:55.474348Z","time spent":"659.092723ms","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T13:18:56.133784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.230928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-19T13:18:56.133798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.054386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-19T13:18:56.133812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.878564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-19T13:18:56.133862Z","caller":"traceutil/trace.go:171","msg":"trace[1255825074] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:781; }","duration":"539.165809ms","start":"2026-05-19T13:18:55.594681Z","end":"2026-05-19T13:18:56.133846Z","steps":["trace[1255825074] 'agreement among raft nodes before linearized reading' (duration: 538.986814ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:18:56.133857Z","caller":"traceutil/trace.go:171","msg":"trace[188220484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:781; }","duration":"464.343092ms","start":"2026-05-19T13:18:55.669496Z","end":"2026-05-19T13:18:56.133839Z","steps":["trace[188220484] 'agreement among raft nodes before linearized reading' (duration: 464.169207ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:18:56.133871Z","caller":"traceutil/trace.go:171","msg":"trace[961282295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:781; }","duration":"461.978906ms","start":"2026-05-19T13:18:55.671874Z","end":"2026-05-19T13:18:56.133853Z","steps":["trace[961282295] 'agreement among raft nodes before linearized reading' (duration: 461.836332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:18:56.133892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:18:55.594661Z","time spent":"539.22528ms","remote":"127.0.0.1:35548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.227\" "} {"level":"warn","ts":"2026-05-19T13:18:56.133897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:18:55.669478Z","time spent":"464.411833ms","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T13:18:56.133907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:18:55.671853Z","time spent":"462.046138ms","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T13:19:31.07328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.754143ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-05-19T13:19:31.568057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.095073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:19:31.568141Z","caller":"traceutil/trace.go:171","msg":"trace[1228794487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1002; }","duration":"323.205035ms","start":"2026-05-19T13:19:31.244905Z","end":"2026-05-19T13:19:31.56811Z","steps":["trace[1228794487] 'range keys from in-memory index tree' (duration: 323.024462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:31.568174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:19:31.24489Z","time spent":"323.277367ms","remote":"127.0.0.1:35520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T13:19:38.678791Z","caller":"traceutil/trace.go:171","msg":"trace[1868625467] linearizableReadLoop","detail":"{readStateIndex:1065; appliedIndex:1064; }","duration":"122.823497ms","start":"2026-05-19T13:19:38.555947Z","end":"2026-05-19T13:19:38.67877Z","steps":["trace[1868625467] 'read index received' (duration: 116.65083ms)","trace[1868625467] 'applied index is now lower than readState.Index' (duration: 6.171667ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:19:38.678911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.93776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T13:19:38.678947Z","caller":"traceutil/trace.go:171","msg":"trace[1355554748] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1027; }","duration":"123.019822ms","start":"2026-05-19T13:19:38.555917Z","end":"2026-05-19T13:19:38.678937Z","steps":["trace[1355554748] 'agreement among raft nodes before linearized reading' (duration: 122.93458ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:19:38.67892Z","caller":"traceutil/trace.go:171","msg":"trace[196752443] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"179.567135ms","start":"2026-05-19T13:19:38.499322Z","end":"2026-05-19T13:19:38.678889Z","steps":["trace[196752443] 'process raft request' (duration: 173.280175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:52.458621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.425354ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T13:19:52.458838Z","caller":"traceutil/trace.go:171","msg":"trace[1217240256] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"450.745535ms","start":"2026-05-19T13:19:52.008078Z","end":"2026-05-19T13:19:52.458824Z","steps":["trace[1217240256] 'process raft request' (duration: 329.042144ms)","trace[1217240256] 'compare' (duration: 121.332212ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:19:52.459719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:19:52.008035Z","time spent":"451.549314ms","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T13:19:52.662414Z","caller":"traceutil/trace.go:171","msg":"trace[2035389692] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"195.00084ms","start":"2026-05-19T13:19:52.467393Z","end":"2026-05-19T13:19:52.662393Z","steps":["trace[2035389692] 'process raft request' (duration: 172.204529ms)","trace[2035389692] 'compare' (duration: 22.543625ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:19:52.838982Z","caller":"traceutil/trace.go:171","msg":"trace[301368433] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"101.423679ms","start":"2026-05-19T13:19:52.737536Z","end":"2026-05-19T13:19:52.838959Z","steps":["trace[301368433] 'process raft request' (duration: 101.307926ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:19:53.351227Z","caller":"traceutil/trace.go:171","msg":"trace[1393576505] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1114; }","duration":"108.808075ms","start":"2026-05-19T13:19:53.242396Z","end":"2026-05-19T13:19:53.351204Z","steps":["trace[1393576505] 'read index received' (duration: 108.62676ms)","trace[1393576505] 'applied index is now lower than readState.Index' (duration: 180.085µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:19:53.351342Z","caller":"traceutil/trace.go:171","msg":"trace[292433701] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"153.973816ms","start":"2026-05-19T13:19:53.197344Z","end":"2026-05-19T13:19:53.351317Z","steps":["trace[292433701] 'process raft request' (duration: 153.68443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:53.351365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.940478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:19:53.351408Z","caller":"traceutil/trace.go:171","msg":"trace[2058531170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1074; }","duration":"109.028849ms","start":"2026-05-19T13:19:53.242364Z","end":"2026-05-19T13:19:53.351393Z","steps":["trace[2058531170] 'agreement among raft nodes before linearized reading' (duration: 108.940067ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:19:54.212157Z","caller":"traceutil/trace.go:171","msg":"trace[880426896] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"233.330382ms","start":"2026-05-19T13:19:53.978796Z","end":"2026-05-19T13:19:54.212126Z","steps":["trace[880426896] 'process raft request' (duration: 233.148908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:54.518026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.822012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:19:54.51813Z","caller":"traceutil/trace.go:171","msg":"trace[1934721953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1075; }","duration":"275.957734ms","start":"2026-05-19T13:19:54.242152Z","end":"2026-05-19T13:19:54.51811Z","steps":["trace[1934721953] 'range keys from in-memory index tree' (duration: 275.713549ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:19:55.483448Z","caller":"traceutil/trace.go:171","msg":"trace[970753458] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"124.741862ms","start":"2026-05-19T13:19:55.358683Z","end":"2026-05-19T13:19:55.483425Z","steps":["trace[970753458] 'process raft request' (duration: 124.612969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:19:55.827534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.127822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-19T13:19:55.827611Z","caller":"traceutil/trace.go:171","msg":"trace[1723319999] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:1081; }","duration":"229.211284ms","start":"2026-05-19T13:19:55.59838Z","end":"2026-05-19T13:19:55.827591Z","steps":["trace[1723319999] 'range keys from in-memory index tree' (duration: 228.949568ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:19:55.989363Z","caller":"traceutil/trace.go:171","msg":"trace[880753417] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"152.686227ms","start":"2026-05-19T13:19:55.836646Z","end":"2026-05-19T13:19:55.989332Z","steps":["trace[880753417] 'process raft request' (duration: 103.457407ms)","trace[880753417] 'compare' (duration: 49.037566ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:20:09.311615Z","caller":"traceutil/trace.go:171","msg":"trace[291433597] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"427.431472ms","start":"2026-05-19T13:20:08.884161Z","end":"2026-05-19T13:20:09.311593Z","steps":["trace[291433597] 'process raft request' (duration: 427.209477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:09.311761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:08.88415Z","time spent":"427.533675ms","remote":"127.0.0.1:35808","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-19T13:20:09.357383Z","caller":"traceutil/trace.go:171","msg":"trace[261766658] linearizableReadLoop","detail":"{readStateIndex:1223; appliedIndex:1221; }","duration":"261.47278ms","start":"2026-05-19T13:20:09.095882Z","end":"2026-05-19T13:20:09.357355Z","steps":["trace[261766658] 'read index received' (duration: 215.588441ms)","trace[261766658] 'applied index is now lower than readState.Index' (duration: 45.882799ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:20:09.357526Z","caller":"traceutil/trace.go:171","msg":"trace[1558584420] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"472.526693ms","start":"2026-05-19T13:20:08.884968Z","end":"2026-05-19T13:20:09.357494Z","steps":["trace[1558584420] 'process raft request' (duration: 472.248767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:09.357584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.640028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T13:20:09.357638Z","caller":"traceutil/trace.go:171","msg":"trace[707896439] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1177; }","duration":"102.752031ms","start":"2026-05-19T13:20:09.254876Z","end":"2026-05-19T13:20:09.357628Z","steps":["trace[707896439] 'agreement among raft nodes before linearized reading' (duration: 102.641619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:09.357664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:08.884959Z","time spent":"472.632485ms","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T13:20:09.357991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.119135ms","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-19T13:20:09.358027Z","caller":"traceutil/trace.go:171","msg":"trace[1388088498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1177; }","duration":"262.192366ms","start":"2026-05-19T13:20:09.095825Z","end":"2026-05-19T13:20:09.358018Z","steps":["trace[1388088498] 'agreement among raft nodes before linearized reading' (duration: 262.067423ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:20:09.592704Z","caller":"traceutil/trace.go:171","msg":"trace[1371774800] linearizableReadLoop","detail":"{readStateIndex:1224; appliedIndex:1223; }","duration":"186.127621ms","start":"2026-05-19T13:20:09.406554Z","end":"2026-05-19T13:20:09.592682Z","steps":["trace[1371774800] 'read index received' (duration: 171.172856ms)","trace[1371774800] 'applied index is now lower than readState.Index' (duration: 14.953225ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:20:09.592831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.260925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T13:20:09.592851Z","caller":"traceutil/trace.go:171","msg":"trace[1243677240] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1178; }","duration":"186.324966ms","start":"2026-05-19T13:20:09.406519Z","end":"2026-05-19T13:20:09.592844Z","steps":["trace[1243677240] 'agreement among raft nodes before linearized reading' (duration: 186.235414ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:20:09.593109Z","caller":"traceutil/trace.go:171","msg":"trace[1749782493] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"231.457328ms","start":"2026-05-19T13:20:09.361561Z","end":"2026-05-19T13:20:09.593018Z","steps":["trace[1749782493] 'process raft request' (duration: 216.118254ms)","trace[1749782493] 'compare' (duration: 14.664778ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:20:12.114266Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052784961690126,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T13:20:12.175885Z","caller":"traceutil/trace.go:171","msg":"trace[1517869653] linearizableReadLoop","detail":"{readStateIndex:1232; appliedIndex:1231; }","duration":"562.361247ms","start":"2026-05-19T13:20:11.6135Z","end":"2026-05-19T13:20:12.175861Z","steps":["trace[1517869653] 'read index received' (duration: 562.129361ms)","trace[1517869653] 'applied index is now lower than readState.Index' (duration: 230.516µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:20:12.175928Z","caller":"traceutil/trace.go:171","msg":"trace[1929435247] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"576.346059ms","start":"2026-05-19T13:20:11.599563Z","end":"2026-05-19T13:20:12.175909Z","steps":["trace[1929435247] 'process raft request' (duration: 576.141834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:12.176039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:11.599539Z","time spent":"576.431561ms","remote":"127.0.0.1:35808","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-19T13:20:12.176069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.557351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-19T13:20:12.176103Z","caller":"traceutil/trace.go:171","msg":"trace[114674972] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1185; }","duration":"562.618162ms","start":"2026-05-19T13:20:11.613475Z","end":"2026-05-19T13:20:12.176093Z","steps":["trace[114674972] 'agreement among raft nodes before linearized reading' (duration: 562.501049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:12.176132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:11.613463Z","time spent":"562.661754ms","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T13:20:12.176109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.175895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T13:20:12.1762Z","caller":"traceutil/trace.go:171","msg":"trace[2054245054] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1185; }","duration":"483.289788ms","start":"2026-05-19T13:20:11.692891Z","end":"2026-05-19T13:20:12.176181Z","steps":["trace[2054245054] 'agreement among raft nodes before linearized reading' (duration: 483.120764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:12.176249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:11.692848Z","time spent":"483.39155ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":5,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-05-19T13:20:12.74619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.819786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T13:20:12.746289Z","caller":"traceutil/trace.go:171","msg":"trace[890020147] linearizableReadLoop","detail":"{readStateIndex:1233; appliedIndex:1232; }","duration":"501.232294ms","start":"2026-05-19T13:20:12.24504Z","end":"2026-05-19T13:20:12.746272Z","steps":["trace[890020147] 'read index received' (duration: 21.307285ms)","trace[890020147] 'applied index is now lower than readState.Index' (duration: 479.924019ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:20:12.746479Z","caller":"traceutil/trace.go:171","msg":"trace[1204558744] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"567.10184ms","start":"2026-05-19T13:20:12.179364Z","end":"2026-05-19T13:20:12.746466Z","steps":["trace[1204558744] 'process raft request' (duration: 86.930535ms)","trace[1204558744] 'compare' (duration: 479.497358ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:20:12.746545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:12.179347Z","time spent":"567.166871ms","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T13:20:12.746734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.699545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:20:12.746779Z","caller":"traceutil/trace.go:171","msg":"trace[638192326] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1186; }","duration":"501.784307ms","start":"2026-05-19T13:20:12.244982Z","end":"2026-05-19T13:20:12.746767Z","steps":["trace[638192326] 'agreement among raft nodes before linearized reading' (duration: 501.722585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:12.746825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:12.244961Z","time spent":"501.854679ms","remote":"127.0.0.1:35520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T13:20:12.748198Z","caller":"traceutil/trace.go:171","msg":"trace[561644775] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"142.620867ms","start":"2026-05-19T13:20:12.605556Z","end":"2026-05-19T13:20:12.748176Z","steps":["trace[561644775] 'process raft request' (duration: 142.428762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:12.748322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.058364ms","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-19T13:20:12.748381Z","caller":"traceutil/trace.go:171","msg":"trace[1813133368] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1187; }","duration":"425.176967ms","start":"2026-05-19T13:20:12.323191Z","end":"2026-05-19T13:20:12.748368Z","steps":["trace[1813133368] 'agreement among raft nodes before linearized reading' (duration: 424.960252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:12.748421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:12.323174Z","time spent":"425.235748ms","remote":"127.0.0.1:35808","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-19T13:20:26.100322Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052784961690245,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T13:20:26.180233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.168028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-19T13:20:26.18032Z","caller":"traceutil/trace.go:171","msg":"trace[1266447614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1229; }","duration":"705.304031ms","start":"2026-05-19T13:20:25.474992Z","end":"2026-05-19T13:20:26.180296Z","steps":["trace[1266447614] 'range keys from in-memory index tree' (duration: 704.968374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:26.180361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:25.47497Z","time spent":"705.381563ms","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-19T13:20:26.180953Z","caller":"traceutil/trace.go:171","msg":"trace[719017555] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"693.102821ms","start":"2026-05-19T13:20:25.487826Z","end":"2026-05-19T13:20:26.180929Z","steps":["trace[719017555] 'process raft request' (duration: 688.41009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:26.181169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:25.487807Z","time spent":"693.266344ms","remote":"127.0.0.1:35598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T13:20:26.257901Z","caller":"traceutil/trace.go:171","msg":"trace[1311913831] linearizableReadLoop","detail":"{readStateIndex:1280; appliedIndex:1278; }","duration":"658.329929ms","start":"2026-05-19T13:20:25.599552Z","end":"2026-05-19T13:20:26.257882Z","steps":["trace[1311913831] 'read index received' (duration: 576.698647ms)","trace[1311913831] 'applied index is now lower than readState.Index' (duration: 81.630092ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:20:26.258094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.526694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-19T13:20:26.258144Z","caller":"traceutil/trace.go:171","msg":"trace[256936066] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:1231; }","duration":"658.624296ms","start":"2026-05-19T13:20:25.599506Z","end":"2026-05-19T13:20:26.25813Z","steps":["trace[256936066] 'agreement among raft nodes before linearized reading' (duration: 658.483473ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:26.258193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:25.599486Z","time spent":"658.696038ms","remote":"127.0.0.1:35548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.227\" "} {"level":"info","ts":"2026-05-19T13:20:26.258021Z","caller":"traceutil/trace.go:171","msg":"trace[2143114383] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"472.543288ms","start":"2026-05-19T13:20:25.78545Z","end":"2026-05-19T13:20:26.257993Z","steps":["trace[2143114383] 'process raft request' (duration: 472.309842ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:26.258421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:25.785422Z","time spent":"472.923846ms","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T13:20:26.502657Z","caller":"traceutil/trace.go:171","msg":"trace[116905372] linearizableReadLoop","detail":"{readStateIndex:1281; appliedIndex:1280; }","duration":"244.668127ms","start":"2026-05-19T13:20:26.257966Z","end":"2026-05-19T13:20:26.502634Z","steps":["trace[116905372] 'read index received' (duration: 197.111122ms)","trace[116905372] 'applied index is now lower than readState.Index' (duration: 47.555985ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:20:26.502702Z","caller":"traceutil/trace.go:171","msg":"trace[966504912] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"318.089385ms","start":"2026-05-19T13:20:26.184579Z","end":"2026-05-19T13:20:26.502668Z","steps":["trace[966504912] 'process raft request' (duration: 270.462378ms)","trace[966504912] 'compare' (duration: 47.490093ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:20:26.502795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.215644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:20:26.502832Z","caller":"traceutil/trace.go:171","msg":"trace[1510070801] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1232; }","duration":"259.305976ms","start":"2026-05-19T13:20:26.243516Z","end":"2026-05-19T13:20:26.502822Z","steps":["trace[1510070801] 'agreement among raft nodes before linearized reading' (duration: 259.231654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:26.502854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:26.184556Z","time spent":"318.220127ms","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T13:20:26.502854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.244966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:20:26.502899Z","caller":"traceutil/trace.go:171","msg":"trace[494380285] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1232; }","duration":"440.332108ms","start":"2026-05-19T13:20:26.062556Z","end":"2026-05-19T13:20:26.502888Z","steps":["trace[494380285] 'agreement among raft nodes before linearized reading' (duration: 440.259156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:26.502929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:26.062536Z","time spent":"440.384199ms","remote":"127.0.0.1:39882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-19T13:20:26.503126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.498223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-05-19T13:20:26.503154Z","caller":"traceutil/trace.go:171","msg":"trace[2018507452] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1232; }","duration":"683.564485ms","start":"2026-05-19T13:20:25.819582Z","end":"2026-05-19T13:20:26.503146Z","steps":["trace[2018507452] 'agreement among raft nodes before linearized reading' (duration: 683.462462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:26.503178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:25.819556Z","time spent":"683.614106ms","remote":"127.0.0.1:35708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5354,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-05-19T13:20:26.546256Z","caller":"traceutil/trace.go:171","msg":"trace[1902877032] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"139.847656ms","start":"2026-05-19T13:20:26.406376Z","end":"2026-05-19T13:20:26.546223Z","steps":["trace[1902877032] 'process raft request' (duration: 139.738244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:26.546437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.735154ms","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-19T13:20:26.546498Z","caller":"traceutil/trace.go:171","msg":"trace[1563710348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1233; }","duration":"235.869187ms","start":"2026-05-19T13:20:26.310619Z","end":"2026-05-19T13:20:26.546489Z","steps":["trace[1563710348] 'agreement among raft nodes before linearized reading' (duration: 235.598061ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:20:29.039065Z","caller":"traceutil/trace.go:171","msg":"trace[1467700005] linearizableReadLoop","detail":"{readStateIndex:1297; appliedIndex:1296; }","duration":"132.457651ms","start":"2026-05-19T13:20:28.906579Z","end":"2026-05-19T13:20:29.039037Z","steps":["trace[1467700005] 'read index received' (duration: 132.198055ms)","trace[1467700005] 'applied index is now lower than readState.Index' (duration: 258.336µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:20:29.039093Z","caller":"traceutil/trace.go:171","msg":"trace[674794503] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"206.367994ms","start":"2026-05-19T13:20:28.832695Z","end":"2026-05-19T13:20:29.039063Z","steps":["trace[674794503] 'process raft request' (duration: 206.185789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:29.039245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.650945ms","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-19T13:20:29.039286Z","caller":"traceutil/trace.go:171","msg":"trace[1717697870] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1247; }","duration":"132.732698ms","start":"2026-05-19T13:20:28.906547Z","end":"2026-05-19T13:20:29.03928Z","steps":["trace[1717697870] 'agreement among raft nodes before linearized reading' (duration: 132.592424ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:20:29.230231Z","caller":"traceutil/trace.go:171","msg":"trace[2092915433] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"186.285955ms","start":"2026-05-19T13:20:29.043923Z","end":"2026-05-19T13:20:29.230208Z","steps":["trace[2092915433] 'process raft request' (duration: 186.238154ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:20:29.230293Z","caller":"traceutil/trace.go:171","msg":"trace[2062090490] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"186.452439ms","start":"2026-05-19T13:20:29.043813Z","end":"2026-05-19T13:20:29.230265Z","steps":["trace[2062090490] 'process raft request' (duration: 144.305994ms)","trace[2062090490] 'compare' (duration: 41.92989ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:20:30.727552Z","caller":"traceutil/trace.go:171","msg":"trace[1614287858] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"117.693078ms","start":"2026-05-19T13:20:30.609837Z","end":"2026-05-19T13:20:30.727531Z","steps":["trace[1614287858] 'process raft request' (duration: 115.909676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:39.944626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.810303ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T13:20:39.94496Z","caller":"traceutil/trace.go:171","msg":"trace[1097340384] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"616.935979ms","start":"2026-05-19T13:20:39.327823Z","end":"2026-05-19T13:20:39.944759Z","steps":["trace[1097340384] 'process raft request' (duration: 228.84685ms)","trace[1097340384] 'compare' (duration: 387.660538ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:20:39.945144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:39.327796Z","time spent":"617.254967ms","remote":"127.0.0.1:35808","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-19T13:20:39.945355Z","caller":"traceutil/trace.go:171","msg":"trace[2083205053] linearizableReadLoop","detail":"{readStateIndex:1389; appliedIndex:1388; }","duration":"590.603721ms","start":"2026-05-19T13:20:39.354454Z","end":"2026-05-19T13:20:39.945058Z","steps":["trace[2083205053] 'read index received' (duration: 202.182284ms)","trace[2083205053] 'applied index is now lower than readState.Index' (duration: 388.418987ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:20:39.945472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.98662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:20:39.945616Z","caller":"traceutil/trace.go:171","msg":"trace[1629889991] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1337; }","duration":"591.128473ms","start":"2026-05-19T13:20:39.354437Z","end":"2026-05-19T13:20:39.945565Z","steps":["trace[1629889991] 'agreement among raft nodes before linearized reading' (duration: 590.946699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:39.945703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:39.354428Z","time spent":"591.231845ms","remote":"127.0.0.1:39910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-19T13:20:39.946205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.097113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-19T13:20:39.946288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.262363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:20:39.94637Z","caller":"traceutil/trace.go:171","msg":"trace[1113125672] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1337; }","duration":"375.327685ms","start":"2026-05-19T13:20:39.570985Z","end":"2026-05-19T13:20:39.946313Z","steps":["trace[1113125672] 'agreement among raft nodes before linearized reading' (duration: 375.282173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:39.946417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:39.570969Z","time spent":"375.438167ms","remote":"127.0.0.1:39948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-19T13:20:39.94627Z","caller":"traceutil/trace.go:171","msg":"trace[1178714887] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1337; }","duration":"451.185644ms","start":"2026-05-19T13:20:39.495068Z","end":"2026-05-19T13:20:39.946253Z","steps":["trace[1178714887] 'agreement among raft nodes before linearized reading' (duration: 450.984299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:39.946216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.800662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T13:20:39.947065Z","caller":"traceutil/trace.go:171","msg":"trace[2096867891] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1337; }","duration":"235.647312ms","start":"2026-05-19T13:20:39.711355Z","end":"2026-05-19T13:20:39.947003Z","steps":["trace[2096867891] 'agreement among raft nodes before linearized reading' (duration: 234.798452ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:20:39.947182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:20:39.495057Z","time spent":"451.699516ms","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-19T13:20:40.071348Z","caller":"traceutil/trace.go:171","msg":"trace[1708308139] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"120.400392ms","start":"2026-05-19T13:20:39.95092Z","end":"2026-05-19T13:20:40.071321Z","steps":["trace[1708308139] 'process raft request' (duration: 120.054864ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:20:40.255863Z","caller":"traceutil/trace.go:171","msg":"trace[50241353] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"179.635705ms","start":"2026-05-19T13:20:40.076193Z","end":"2026-05-19T13:20:40.255828Z","steps":["trace[50241353] 'process raft request' (duration: 176.352837ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:10.727104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.330601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:21:10.727166Z","caller":"traceutil/trace.go:171","msg":"trace[53444115] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1476; }","duration":"483.436434ms","start":"2026-05-19T13:21:10.243717Z","end":"2026-05-19T13:21:10.727154Z","steps":["trace[53444115] 'range keys from in-memory index tree' (duration: 483.244879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:10.727191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:10.243699Z","time spent":"483.487716ms","remote":"127.0.0.1:35520","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-19T13:21:10.727115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.213164ms","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-19T13:21:10.727225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.286778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-19T13:21:10.727292Z","caller":"traceutil/trace.go:171","msg":"trace[1402463037] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1476; }","duration":"513.434329ms","start":"2026-05-19T13:21:10.21384Z","end":"2026-05-19T13:21:10.727274Z","steps":["trace[1402463037] 'range keys from in-memory index tree' (duration: 513.07327ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:21:10.727339Z","caller":"traceutil/trace.go:171","msg":"trace[1746121093] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1476; }","duration":"423.469683ms","start":"2026-05-19T13:21:10.303844Z","end":"2026-05-19T13:21:10.727313Z","steps":["trace[1746121093] 'range keys from in-memory index tree' (duration: 423.101854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:10.72737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:10.21379Z","time spent":"513.567942ms","remote":"127.0.0.1:35808","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-19T13:21:10.727406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:10.303829Z","time spent":"423.557964ms","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-19T13:21:11.119762Z","caller":"traceutil/trace.go:171","msg":"trace[507259036] transaction","detail":"{read_only:false; response_revision:1479; number_of_response:1; }","duration":"386.736306ms","start":"2026-05-19T13:21:10.733001Z","end":"2026-05-19T13:21:11.119737Z","steps":["trace[507259036] 'process raft request' (duration: 386.692395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:11.119917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:10.732995Z","time spent":"386.855419ms","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T13:21:11.120029Z","caller":"traceutil/trace.go:171","msg":"trace[1160927837] transaction","detail":"{read_only:false; response_revision:1478; number_of_response:1; }","duration":"389.018391ms","start":"2026-05-19T13:21:10.73097Z","end":"2026-05-19T13:21:11.119989Z","steps":["trace[1160927837] 'process raft request' (duration: 388.616892ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:21:11.12004Z","caller":"traceutil/trace.go:171","msg":"trace[913513046] linearizableReadLoop","detail":"{readStateIndex:1537; appliedIndex:1537; }","duration":"354.257852ms","start":"2026-05-19T13:21:10.765749Z","end":"2026-05-19T13:21:11.120006Z","steps":["trace[913513046] 'read index received' (duration: 354.245571ms)","trace[913513046] 'applied index is now lower than readState.Index' (duration: 9.691µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T13:21:11.120192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:10.730957Z","time spent":"389.142924ms","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T13:21:11.120313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.536578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-19T13:21:11.120351Z","caller":"traceutil/trace.go:171","msg":"trace[1222538781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1479; }","duration":"354.612739ms","start":"2026-05-19T13:21:10.765726Z","end":"2026-05-19T13:21:11.120338Z","steps":["trace[1222538781] 'agreement among raft nodes before linearized reading' (duration: 354.430885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:11.120387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:10.765714Z","time spent":"354.664291ms","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T13:21:11.137042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.984054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T13:21:11.137102Z","caller":"traceutil/trace.go:171","msg":"trace[1162827873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1480; }","duration":"341.064736ms","start":"2026-05-19T13:21:10.796024Z","end":"2026-05-19T13:21:11.137088Z","steps":["trace[1162827873] 'agreement among raft nodes before linearized reading' (duration: 340.859921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:21:11.137136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T13:21:10.796012Z","time spent":"341.115698ms","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-19T13:23:52.025974Z","caller":"traceutil/trace.go:171","msg":"trace[471626050] transaction","detail":"{read_only:false; response_revision:2246; number_of_response:1; }","duration":"198.148188ms","start":"2026-05-19T13:23:51.827778Z","end":"2026-05-19T13:23:52.025926Z","steps":["trace[471626050] 'process raft request' (duration: 183.12556ms)","trace[471626050] 'compare' (duration: 14.793612ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:23:52.074242Z","caller":"traceutil/trace.go:171","msg":"trace[520482623] linearizableReadLoop","detail":"{readStateIndex:2339; appliedIndex:2337; }","duration":"133.113848ms","start":"2026-05-19T13:23:51.941101Z","end":"2026-05-19T13:23:52.074215Z","steps":["trace[520482623] 'read index received' (duration: 69.813269ms)","trace[520482623] 'applied index is now lower than readState.Index' (duration: 63.299479ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T13:23:52.074406Z","caller":"traceutil/trace.go:171","msg":"trace[1583456729] transaction","detail":"{read_only:false; response_revision:2247; number_of_response:1; }","duration":"226.563854ms","start":"2026-05-19T13:23:51.847822Z","end":"2026-05-19T13:23:52.074386Z","steps":["trace[1583456729] 'process raft request' (duration: 226.257747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:23:52.074717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.72822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-19T13:23:52.074719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.318915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T13:23:52.074771Z","caller":"traceutil/trace.go:171","msg":"trace[1815879943] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2247; }","duration":"121.822382ms","start":"2026-05-19T13:23:51.952933Z","end":"2026-05-19T13:23:52.074756Z","steps":["trace[1815879943] 'agreement among raft nodes before linearized reading' (duration: 121.672339ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T13:23:52.074829Z","caller":"traceutil/trace.go:171","msg":"trace[928792495] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:2247; }","duration":"129.480909ms","start":"2026-05-19T13:23:51.945301Z","end":"2026-05-19T13:23:52.074781Z","steps":["trace[928792495] 'agreement among raft nodes before linearized reading' (duration: 129.317655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T13:23:52.074955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.852536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-05-19T13:23:52.075009Z","caller":"traceutil/trace.go:171","msg":"trace[915880514] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2247; }","duration":"133.938068ms","start":"2026-05-19T13:23:51.941056Z","end":"2026-05-19T13:23:52.074994Z","steps":["trace[915880514] 'agreement among raft nodes before linearized reading' (duration: 133.445076ms)"],"step_count":1}